2026-07-28 15:32:17.359 T:4090 debug : CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr) 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2026-07-28 15:32:17.359 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr) 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2026-07-28 15:32:17.360 T:4090 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2026-07-28 15:32:17.360 T:4090 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2026-07-28 15:32:17.361 T:4090 debug : Radio UECP (RDS) Processor - new CDVDRadioRDSData 2026-07-28 15:32:17.361 T:4090 debug : Audio ID3 tag processor - new CVideoPlayerAudioID3 2026-07-28 15:32:17.361 T:4090 info : VideoPlayer::OpenFile: pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr 2026-07-28 15:32:17.361 T:4090 debug : GLES: HQ scaler precision=10, intermediate format=0x8059 2026-07-28 15:32:17.361 T:4441 debug : Thread VideoPlayer start, auto delete: false 2026-07-28 15:32:17.361 T:4090 debug : virtual void CApplicationPlayerCallback::OnPlayBackStarted(const CFileItem&): call 2026-07-28 15:32:17.361 T:4441 info : Creating InputStream 2026-07-28 15:32:17.362 T:4441 debug : pvr.vuplus - OpenLiveStream: channel=1041373498 2026-07-28 15:32:17.362 T:4441 debug : pvr.vuplus - StreamReader StreamReader: Started; url=http://192.168.X.Y:8001/1:0:19:5274:41D:1:C00000:0:0:0 2026-07-28 15:32:17.362 T:4441 debug : CurlFile::Open - 2026-07-28 15:32:17.366 T:4425 debug : Loading settings for pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr 2026-07-28 15:32:17.402 T:4090 debug : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr) 2026-07-28 15:32:17.402 T:4090 debug : CVideoGUIInfo::InitCurrentItem(pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr) 2026-07-28 15:32:17.402 T:4090 debug : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr) 2026-07-28 15:32:17.402 T:4119 debug : PushCecKeypress - received key b duration 75 2026-07-28 15:32:17.409 T:4117 debug : [plugin.video.youtube] player_monitor:426(onPlayBackStarted) - Clear property 'busy' 2026-07-28 15:32:17.409 T:4117 debug : [plugin.video.youtube] player_monitor:428(onPlayBackStarted) - Get property 'play_using': '' 2026-07-28 15:32:17.417 T:4125 debug : trying to load setting definitions from old format... 2026-07-28 15:32:17.502 T:4090 debug : ------ Window Init (DialogBusy.xml) ------ 2026-07-28 15:32:17.503 T:4090 debug : CDirectoryProvider[pvr://tv/]: refreshing... 2026-07-28 15:32:17.503 T:4090 debug : CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing... 2026-07-28 15:32:17.503 T:4090 debug : CDirectoryProvider[pvr://channels/tv]: refreshing... 2026-07-28 15:32:17.503 T:4090 debug : CDirectoryProvider[pvr://channels/tv/*?view=dateadded]: refreshing... 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: Database driver: Internal (sqlite) 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:StoreSQLite]: StoreSQLite DBFile: /storage/.kodi/userdata/addon_data/plugin.video.mediathekview/filmliste-v3.db 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: Last Update 2026-06-15 20:06:47 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: Last Full Update 2026-06-15 20:06:47 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: version 3 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: status IDLE 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: update interval 7200 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: Update Mode "Automatic" 2026-07-28 15:32:17.971 T:4116 debug : [plugin.video.mediathekview-1.1.3:MediathekViewUpdater]: nothing to do 2026-07-28 15:32:18.209 T:4117 debug : [plugin.video.youtube] service_runner:138(run) - Clear property 'plugin_sleeping' 2026-07-28 15:32:18.437 T:4117 debug : [plugin.video.youtube] service_runner:244(run) - Set property 'plugin_sleeping': 'true' 2026-07-28 15:32:20.061 T:4441 debug : virtual bool CInputStreamPVRChannel::OpenPVRStream(): Opened channel stream pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr 2026-07-28 15:32:20.061 T:4441 info : Creating Demuxer 2026-07-28 15:32:20.063 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 1 2026-07-28 15:32:20.063 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 2 2026-07-28 15:32:20.063 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 3 2026-07-28 15:32:20.063 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 4 2026-07-28 15:32:20.063 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 5 2026-07-28 15:32:20.063 T:4441 info : Opening stream: 3 source: 256 2026-07-28 15:32:20.063 T:4441 info : [WHITELIST] Searching the whitelist for: width: 0, height: 0, fps: 50.000, 3D: false 2026-07-28 15:32:20.063 T:4441 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2026-07-28 15:32:20.064 T:4441 debug : [WHITELIST] No match for an exact resolution with an exact refresh rate 2026-07-28 15:32:20.064 T:4441 debug : [WHITELIST] Searching for an exact resolution with double the refresh rate 2026-07-28 15:32:20.064 T:4441 debug : [WHITELIST] No match for an exact resolution with double the refresh rate 2026-07-28 15:32:20.064 T:4441 debug : [WHITELIST] Searching for a desktop resolution with an exact refresh rate 2026-07-28 15:32:20.064 T:4441 debug : [WHITELIST] Matched a desktop resolution with an exact refresh rate 3840x2160 @ 50.000000 Hz (27) 2026-07-28 15:32:20.064 T:4441 info : Display resolution ADJUST : 3840x2160 @ 50.000000 Hz (27) (weight: 0.000) 2026-07-28 15:32:20.083 T:4090 debug : OnLostDevice - notify display change event 2026-07-28 15:32:20.084 T:4090 info : VideoPlayer: OnLostDisplay received 2026-07-28 15:32:20.084 T:4090 debug : Flush - flushing renderer 2026-07-28 15:32:20.084 T:4090 debug : virtual bool KODI::WINDOWING::GBM::CDRMUtils::SetMode(const RESOLUTION_INFO&): Found crtc mode: 3840x2160 @ 50 Hz 2026-07-28 15:32:20.084 T:4090 debug : virtual void KODI::WINDOWING::GBM::CDRMAtomic::FlipPage(gbm_bo*, bool, bool, bool): Execute modeset at next commit 2026-07-28 15:32:20.137 T:4090 info : GLES: Maximum texture width: 4096 2026-07-28 15:32:20.139 T:4441 info : Creating video codec: h264 2026-07-28 15:32:20.139 T:4441 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper 2026-07-28 15:32:20.139 T:4441 error : ffmpeg[0x38bd5da0]: [h264_v4l2m2m] Could not find a valid device 2026-07-28 15:32:20.139 T:4441 error : ffmpeg[0x38bd5da0]: [h264_v4l2m2m] can't configure decoder 2026-07-28 15:32:20.139 T:4441 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2026-07-28 15:32:20.139 T:4441 info : CDVDVideoCodecDRMPRIME::Open - using decoder H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2026-07-28 15:32:20.139 T:4441 debug : CVideoPlayerVideo::OpenStream - open stream with codec: h264 2026-07-28 15:32:20.143 T:4441 info : Creating video thread 2026-07-28 15:32:20.144 T:4446 debug : Thread VideoPlayerVideo start, auto delete: false 2026-07-28 15:32:20.144 T:4446 info : running thread: video_thread 2026-07-28 15:32:20.147 T:4441 debug : bool CEdl::ReadEditDecisionLists(const CFileItem&, float, std::chrono::milliseconds): Checking for edit decision lists (EDL) for: pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr 2026-07-28 15:32:20.147 T:4446 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: true 2026-07-28 15:32:20.153 T:4090 debug : CWinSystemGbmGLESContext::PresentRender - Sending display reset to all clients 2026-07-28 15:32:20.153 T:4090 info : VideoPlayer: OnResetDisplay received 2026-07-28 15:32:20.153 T:4090 warning : CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 2026-07-28 15:32:20.155 T:4441 debug : CCBitstreamParserFactory: Creating H264AnnexBBitstreamParser (detected Annex B format) 2026-07-28 15:32:20.155 T:4441 debug : CVideoPlayer::OpenStream: m_bFullScreenVideo set pre-Configure, viewport 3840x2160 2026-07-28 15:32:20.155 T:4446 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2026-07-28 15:32:20.155 T:4441 info : Opening stream: 4 source: 256 2026-07-28 15:32:20.155 T:4441 info : Finding audio codec for: 86019 2026-07-28 15:32:20.155 T:4441 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2026-07-28 15:32:20.155 T:4441 info : void CVideoPlayerAudio::OpenStream(CDVDStreamInfo&, std::unique_ptr): Allowing max Out-Of-Sync Value of 50 ms 2026-07-28 15:32:20.155 T:4441 info : Creating audio thread 2026-07-28 15:32:20.155 T:4447 debug : Thread VideoPlayerAudio start, auto delete: false 2026-07-28 15:32:20.155 T:4441 info : Opening stream: 1 source: 256 2026-07-28 15:32:20.156 T:4441 debug : Created subtitles overlay codec: ff-dvbsub 2026-07-28 15:32:20.156 T:4441 debug : CVideoPlayer::SetCaching - caching state 2 2026-07-28 15:32:20.156 T:4441 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2026-07-28 15:32:20.156 T:4447 info : running thread: CVideoPlayerAudio::Process() 2026-07-28 15:32:20.156 T:4447 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: true 2026-07-28 15:32:20.156 T:4447 debug : CDVDAudio::Pause - pausing audio stream 2026-07-28 15:32:20.206 T:4441 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2026-07-28 15:32:20.206 T:4441 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2026-07-28 15:32:20.206 T:4421 debug : virtual void CApplicationPlayerCallback::OnAVChange(): call 2026-07-28 15:32:20.206 T:4441 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2026-07-28 15:32:20.206 T:4421 debug : virtual void CApplicationPlayerCallback::OnAVChange(): call 2026-07-28 15:32:20.206 T:4441 info : CDVDDemuxFFmpeg::Read() stream change 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Input #0, mpegts, from 'pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr': 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Duration: N/A, bitrate: N/A 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Program 21100 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Program 21103 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Program 21104 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Program 21107 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Program 21108 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:1[0x25](deu): Subtitle: dvb_subtitle ([6][0][0][0] / 0x0006), start 64480.553233 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:2[0x32]: Data: scte_35 (CUEI / 0x49455543) 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:3[0x2ff]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:4[0x303](deu): Audio: ac3 ([6][0][0][0] / 0x0006), 0 channels (clean effects) 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:5[0x305]: Data: none 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Program 21118 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Program 21199 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: No Program 2026-07-28 15:32:20.206 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:0[0x12]: Data: epg 2026-07-28 15:32:20.206 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 1 2026-07-28 15:32:20.206 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 2 2026-07-28 15:32:20.206 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 3 2026-07-28 15:32:20.206 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 4 2026-07-28 15:32:20.206 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 5 2026-07-28 15:32:20.206 T:4441 info : Opening stream: 3 source: 256 2026-07-28 15:32:20.206 T:4441 info : Opening stream: 4 source: 256 2026-07-28 15:32:20.206 T:4441 info : Opening stream: 1 source: 256 2026-07-28 15:32:20.206 T:4441 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2026-07-28 15:32:20.207 T:4441 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2026-07-28 15:32:20.207 T:4441 error : ffmpeg[0x38bd5da0]: [NULL] non-existing PPS 0 referenced 2026-07-28 15:32:20.207 T:4441 error : Skipped 20 duplicate messages.. 2026-07-28 15:32:20.207 T:4441 info : CDVDDemuxFFmpeg::Read() stream change 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Input #0, mpegts, from 'pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr': 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Duration: N/A, bitrate: N/A 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Program 21100 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Program 21103 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Program 21104 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Program 21107 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Program 21108 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:1[0x25](deu): Subtitle: dvb_subtitle ([6][0][0][0] / 0x0006), start 64480.553233 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:2[0x32]: Data: scte_35 (CUEI / 0x49455543) 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:3[0x2ff]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn, start 64480.613233 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:4[0x303](deu): Audio: ac3 ([6][0][0][0] / 0x0006), 48000 Hz, stereo, 384 kb/s, start 64480.093233 (clean effects) 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:5[0x305]: Data: none 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Program 21118 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Program 21199 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: No Program 2026-07-28 15:32:20.207 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:0[0x12]: Data: epg 2026-07-28 15:32:20.207 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 1 2026-07-28 15:32:20.207 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 2 2026-07-28 15:32:20.207 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 3 2026-07-28 15:32:20.207 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 4 2026-07-28 15:32:20.207 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 5 2026-07-28 15:32:20.208 T:4441 info : Opening stream: 3 source: 256 2026-07-28 15:32:20.208 T:4441 info : Opening stream: 4 source: 256 2026-07-28 15:32:20.208 T:4441 info : Finding audio codec for: 86019 2026-07-28 15:32:20.208 T:4441 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2026-07-28 15:32:20.208 T:4447 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2026-07-28 15:32:20.208 T:4441 info : Opening stream: 1 source: 256 2026-07-28 15:32:20.208 T:4441 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2026-07-28 15:32:20.208 T:4441 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2026-07-28 15:32:20.208 T:4425 debug : virtual void CApplicationPlayerCallback::OnAVChange(): call 2026-07-28 15:32:20.208 T:4441 error : ffmpeg[0x38bd5da0]: [NULL] non-existing PPS 0 referenced 2026-07-28 15:32:20.422 T:4125 error : Skipped 7 duplicate messages.. 2026-07-28 15:32:20.422 T:4125 debug : trying to load setting definitions from old format... 2026-07-28 15:32:21.039 T:4441 error : ffmpeg[0x38bd5da0]: [NULL] non-existing PPS 0 referenced 2026-07-28 15:32:21.040 T:4441 debug : FFmpegExtraData GetPacketExtradata(const AVPacket*, const AVCodecParameters*): fetching extradata, extradata_size(41) 2026-07-28 15:32:21.040 T:4441 info : CDVDDemuxFFmpeg::Read() stream change 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Input #0, mpegts, from 'pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr': 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Duration: N/A, bitrate: N/A 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Program 21100 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Program 21103 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Program 21104 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Program 21107 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Program 21108 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:1[0x25](deu): Subtitle: dvb_subtitle ([6][0][0][0] / 0x0006), start 64480.553233 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:2[0x32]: Data: scte_35 (CUEI / 0x49455543) 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:3[0x2ff]: Video: h264 ([27][0][0][0] / 0x001B), none, 1440x1080, 90k tbn, start 64480.613233 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:4[0x303](deu): Audio: ac3 ([6][0][0][0] / 0x0006), 48000 Hz, stereo, 384 kb/s, start 64480.093233 (clean effects) 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:5[0x305]: Data: none 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Program 21118 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Program 21199 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: No Program 2026-07-28 15:32:21.040 T:4441 info : ffmpeg[0x38bd5da0]: Stream #0:0[0x12]: Data: epg 2026-07-28 15:32:21.040 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 1 2026-07-28 15:32:21.040 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 2 2026-07-28 15:32:21.040 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 3 2026-07-28 15:32:21.040 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 4 2026-07-28 15:32:21.040 T:4441 debug : CDVDDemuxFFmpeg::AddStream ID: 5 2026-07-28 15:32:21.040 T:4441 info : Opening stream: 3 source: 256 2026-07-28 15:32:21.040 T:4441 info : Creating video codec: h264 2026-07-28 15:32:21.040 T:4441 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper 2026-07-28 15:32:21.040 T:4441 error : ffmpeg[0x38bd5da0]: [h264_v4l2m2m] Could not find a valid device 2026-07-28 15:32:21.040 T:4441 error : ffmpeg[0x38bd5da0]: [h264_v4l2m2m] can't configure decoder 2026-07-28 15:32:21.040 T:4441 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2026-07-28 15:32:21.040 T:4441 info : CDVDVideoCodecDRMPRIME::Open - using decoder H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2026-07-28 15:32:21.041 T:4446 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2026-07-28 15:32:21.041 T:4441 debug : bool CEdl::ReadEditDecisionLists(const CFileItem&, float, std::chrono::milliseconds): Checking for edit decision lists (EDL) for: pvr://channels/tv/Alle%20Sender@-1/1@pvr.vuplus_1041373498.pvr 2026-07-28 15:32:21.041 T:4446 debug : CDVDVideoCodecDRMPRIME::GetPicture - flush buffers 2026-07-28 15:32:21.041 T:4446 debug : CVideoPlayerVideo::OpenStream - open stream with codec: h264 2026-07-28 15:32:21.045 T:4441 debug : CCBitstreamParserFactory: Creating H264AnnexBBitstreamParser (detected Annex B format) 2026-07-28 15:32:21.045 T:4441 info : Opening stream: 4 source: 256 2026-07-28 15:32:21.045 T:4441 info : Opening stream: 1 source: 256 2026-07-28 15:32:21.045 T:4441 debug : CVideoPlayer::HandleMessages - player started 2 2026-07-28 15:32:21.045 T:4441 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2026-07-28 15:32:21.045 T:4446 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2026-07-28 15:32:21.051 T:4446 error : ffmpeg[0x38bd8450]: [h264] mmco: unref short failure 2026-07-28 15:32:21.051 T:4446 error : Skipped 1 duplicate messages.. 2026-07-28 15:32:21.051 T:4446 error : ffmpeg[0x38bd8450]: [h264] number of reference frames (0+5) exceeds max (4; probably corrupt input), discarding one 2026-07-28 15:32:21.052 T:4447 info : CVideoPlayerAudio: display reset occurred, checking for passthrough 2026-07-28 15:32:21.053 T:4447 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2026-07-28 15:32:21.053 T:4447 info : Creating audio stream (codec: ac3, channels: 2, sample rate: 48000, no pass-through) 2026-07-28 15:32:21.054 T:4447 debug : CVideoPlayerAudio:: synctype set to 1: resample 2026-07-28 15:32:21.069 T:4446 debug : CDVDVideoCodecDRMPRIME::FilterTest - found deinterlacing filter bwdif 2026-07-28 15:32:21.070 T:4097 info : CActiveAESink::OpenSink - initialize sink 2026-07-28 15:32:21.090 T:4446 error : ffmpeg[0x38bd8450]: [h264] mmco: unref short failure 2026-07-28 15:32:21.118 T:4446 error : Skipped 1 duplicate messages.. 2026-07-28 15:32:21.118 T:4446 debug : CRenderManager::Configure - change configuration. 1440x1080. display: 1440x808. framerate: 50.00. 2026-07-28 15:32:21.135 T:4090 debug : DeleteRenderer - deleting renderer 2026-07-28 15:32:21.135 T:4090 debug : LinuxRendererGLES: Cleaning up GLES resources 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (AR24) and modifier (LINEAR) 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 107 zpos: 1[1-17], immutable: false 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 118 zpos: 2[1-17], immutable: false 2026-07-28 15:32:21.148 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 129 zpos: 3[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 140 zpos: 4[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 151 zpos: 5[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 162 zpos: 6[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 173 zpos: 7[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 184 zpos: 8[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 195 zpos: 9[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 206 zpos: 10[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 217 zpos: 11[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 228 zpos: 12[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 239 zpos: 13[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 250 zpos: 14[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 261 zpos: 15[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 272 zpos: 16[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (AR24) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (YU12) and modifier (LINEAR) 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): zpos_available: true 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 107 zpos: 1[1-17], immutable: false 2026-07-28 15:32:21.149 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::MoveOnTopOf(KODI::WINDOWING::GBM::CDRMPlane*): plane 83 zpos: 0[0-0], immutable: false 2026-07-28 15:32:21.149 T:4090 info : bool KODI::WINDOWING::GBM::CDRMUtils::FindVideoAndGuiPlane(uint32_t, uint64_t, uint64_t, uint64_t): Using gui plane [3840x2160] id:107, video plane [1440x1080] id:83 on crtc id:94 for video format:YU12, video modifier:LINEAR 2026-07-28 15:32:21.149 T:4090 debug : virtual void KODI::WINDOWING::GBM::CWinSystemGbm::SetColorimetry(const VideoPicture*): setting connector colorspace to Default (source BT709_YCC) 2026-07-28 15:32:21.149 T:4090 debug : CRendererDRMPRIME::Configure: HDR passthrough: off 2026-07-28 15:32:21.149 T:4090 debug : CBaseRenderer::ManageRenderArea: viewRect 0x0 -> 3840x2160 2026-07-28 15:32:21.149 T:4090 debug : CRenderManager::Configure - 5 2026-07-28 15:32:21.150 T:4090 debug : CVideoLayerBridgeDRMPRIME::Configure - setting max bpc to 8 (true) 2026-07-28 15:32:21.152 T:4090 debug : virtual void KODI::WINDOWING::GBM::CDRMAtomic::FlipPage(gbm_bo*, bool, bool, bool): Execute modeset at next commit 2026-07-28 15:32:21.175 T:4090 info : [WHITELIST] Searching the whitelist for: width: 1440, height: 1080, fps: 50.000, 3D: false 2026-07-28 15:32:21.175 T:4090 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2026-07-28 15:32:21.175 T:4090 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 Hz (39) 2026-07-28 15:32:21.175 T:4090 debug : [WHITELIST] Searching for an exact resolution with double the refresh rate 2026-07-28 15:32:21.175 T:4090 info : Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (39) (weight: 0.000) 2026-07-28 15:32:21.175 T:4090 debug : CWinSystemGbmGLESContext::SetFullScreen - resolution changed, creating a new window 2026-07-28 15:32:21.175 T:4090 debug : OnLostDevice - notify display change event 2026-07-28 15:32:21.270 T:4097 debug : CActiveAESink::OpenSink - trying to open device ALSA:@ 2026-07-28 15:32:21.270 T:4097 info : CAESinkALSA::Initialize - Attempting to open device "@" 2026-07-28 15:32:21.271 T:4097 info : CAESinkALSA::Initialize - Opened device "sysdefault" 2026-07-28 15:32:21.271 T:4097 info : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2026-07-28 15:32:21.271 T:4097 info : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2026-07-28 15:32:21.271 T:4097 debug : CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 2026-07-28 15:32:21.271 T:4097 debug : CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 2026-07-28 15:32:21.271 T:4097 debug : CAESinkALSA::InitializeHW - Setting timeout to 200 ms 2026-07-28 15:32:21.271 T:4097 debug : CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 2026-07-28 15:32:21.271 T:4097 debug : CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR 2026-07-28 15:32:21.271 T:4097 debug : CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR) 2026-07-28 15:32:21.271 T:4097 debug : CActiveAESink::OpenSink - ALSA Initialized: 2026-07-28 15:32:21.271 T:4097 debug : Output Device : Default (vc4-hdmi-0 MAI PCM i2s-hifi-0) () 2026-07-28 15:32:21.271 T:4097 debug : Sample Rate : 48000 2026-07-28 15:32:21.271 T:4097 debug : Sample Format : AE_FMT_S24NE3 2026-07-28 15:32:21.271 T:4097 debug : Channel Count : 2 2026-07-28 15:32:21.271 T:4097 debug : Channel Layout: FL, FR 2026-07-28 15:32:21.271 T:4097 debug : Frames : 2400 2026-07-28 15:32:21.271 T:4097 debug : Frame Size : 6 2026-07-28 15:32:21.272 T:4096 debug : void ActiveAE::CActiveAE::ConfigureLowLatency(): Low latency mode: true - Initial buffer level: 100ms (resample: true) 2026-07-28 15:32:21.272 T:4096 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2026-07-28 15:32:21.272 T:4096 debug : void ActiveAE::CActiveAE::ConfigureLowLatency(): Low latency mode: true - Initial buffer level: 100ms (resample: true) 2026-07-28 15:32:21.272 T:4090 info : VideoPlayer: OnLostDisplay received 2026-07-28 15:32:21.272 T:4090 debug : Flush - flushing renderer 2026-07-28 15:32:21.272 T:4446 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: true 2026-07-28 15:32:21.272 T:4090 debug : void {anonymous}::DrmFbDestroyCallback(gbm_bo*, void*): Removing framebuffer: 684 2026-07-28 15:32:21.272 T:4447 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: true 2026-07-28 15:32:21.272 T:4090 debug : void {anonymous}::DrmFbDestroyCallback(gbm_bo*, void*): Removing framebuffer: 683 2026-07-28 15:32:21.292 T:4090 debug : void {anonymous}::DrmFbDestroyCallback(gbm_bo*, void*): Removing framebuffer: 679 2026-07-28 15:32:21.292 T:4090 debug : void {anonymous}::DrmFbDestroyCallback(gbm_bo*, void*): Removing framebuffer: 685 2026-07-28 15:32:21.293 T:4090 debug : CWinSystemGbmEGLContext::DestroyWindow - deinitialized GBM 2026-07-28 15:32:21.293 T:4090 debug : virtual bool KODI::WINDOWING::GBM::CDRMUtils::SetMode(const RESOLUTION_INFO&): Found crtc mode: 1920x1080 @ 50 Hz 2026-07-28 15:32:21.293 T:4090 debug : CGBMUtils::CreateSurface - created surface with size 1920x1080 2026-07-28 15:32:21.295 T:4090 debug : bool KODI::WINDOWING::GBM::CDRMPlane::SupportsFormatAndModifier(uint32_t, uint64_t): found plane format (AR24) and modifier (LINEAR) 2026-07-28 15:32:21.295 T:4090 info : bool KODI::WINDOWING::GBM::CDRMUtils::FindGuiPlane(uint32_t, uint64_t): Using existing gui plane [1920x1080] id:107, format:AR24, modifier:LINEAR, crtc id:94 2026-07-28 15:32:21.295 T:4090 debug : CWinSystemGbmEGLContext::CreateNewWindow - initialized GBM 2026-07-28 15:32:21.297 T:4090 debug : OnLostDevice - notify display change event 2026-07-28 15:32:21.297 T:4090 info : VideoPlayer: OnLostDisplay received 2026-07-28 15:32:21.297 T:4090 debug : Flush - flushing renderer 2026-07-28 15:32:21.297 T:4446 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: true 2026-07-28 15:32:21.297 T:4090 debug : virtual bool KODI::WINDOWING::GBM::CDRMUtils::SetMode(const RESOLUTION_INFO&): Found crtc mode: 1920x1080 @ 50 Hz 2026-07-28 15:32:21.297 T:4447 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: true 2026-07-28 15:32:21.297 T:4090 debug : CVideoLayerBridgeDRMPRIME::Disable - setting max bpc to 8 (true) 2026-07-28 15:32:21.297 T:4090 debug : virtual void KODI::WINDOWING::GBM::CDRMAtomic::FlipPage(gbm_bo*, bool, bool, bool): Execute modeset at next commit 2026-07-28 15:32:21.348 T:4090 info : GLES: Maximum texture width: 4096 2026-07-28 15:32:21.354 T:4090 debug : CBaseRenderer::ManageRenderArea: viewRect 3840x2160 -> 1920x1080 2026-07-28 15:32:21.407 T:4090 debug : CWinSystemGbmGLESContext::PresentRender - Sending display reset to all clients 2026-07-28 15:32:21.407 T:4090 info : VideoPlayer: OnResetDisplay received 2026-07-28 15:32:21.407 T:4447 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2026-07-28 15:32:21.407 T:4446 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2026-07-28 15:32:21.407 T:4447 info : CVideoPlayerAudio: display reset occurred, checking for passthrough 2026-07-28 15:32:21.407 T:4447 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2026-07-28 15:32:21.455 T:4441 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2026-07-28 15:32:21.455 T:4441 debug : CVideoPlayer::HandleMessages - player started 2 2026-07-28 15:32:21.455 T:4421 debug : virtual void CApplicationPlayerCallback::OnAVChange(): call 2026-07-28 15:32:21.907 T:4441 debug : Skipped 1 duplicate messages.. 2026-07-28 15:32:21.907 T:4441 debug : CVideoPlayer::HandleMessages - player started 1 2026-07-28 15:32:21.907 T:4421 debug : virtual void CApplicationPlayerCallback::OnAVChange(): call 2026-07-28 15:32:21.907 T:4441 debug : VideoPlayer::Sync - Audio - pts: 149234.000000, cache: 485181.052785, totalcache: 800000.011921 2026-07-28 15:32:21.907 T:4441 debug : VideoPlayer::Sync - Video - pts: 353234.000000, cache: 50000.000000, totalcache: 100000.000000 2026-07-28 15:32:21.907 T:4447 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-1050766.011921), level: 4, cache: 483277.478119 2026-07-28 15:32:21.907 T:4447 debug : CDVDAudio::Resume - resume audio stream 2026-07-28 15:32:21.907 T:4423 debug : virtual void CApplicationPlayerCallback::OnAVStarted(const CFileItem&): call 2026-07-28 15:32:21.907 T:4096 debug : ActiveAE - start sync of audio stream 2026-07-28 15:32:21.907 T:4446 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-1050766.011921) 2026-07-28 15:32:21.908 T:4090 debug : Activating window ID: 12005 2026-07-28 15:32:21.908 T:4090 debug : ------ Window Deinit (Home.xml) ------ 2026-07-28 15:32:21.908 T:4090 debug : FreeVisualisation() done 2026-07-28 15:32:21.909 T:4090 debug : ------ Window Init (VideoFullScreen.xml) ------ 2026-07-28 15:32:21.910 T:4090 info : [WHITELIST] Searching the whitelist for: width: 1440, height: 1080, fps: 50.000, 3D: false 2026-07-28 15:32:21.910 T:4090 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2026-07-28 15:32:21.910 T:4090 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 Hz (39) 2026-07-28 15:32:21.910 T:4090 debug : [WHITELIST] Searching for an exact resolution with double the refresh rate 2026-07-28 15:32:21.910 T:4441 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2026-07-28 15:32:21.910 T:4090 info : Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (39) (weight: 0.000) 2026-07-28 15:32:21.910 T:4423 debug : virtual void CApplicationPlayerCallback::OnAVChange(): call 2026-07-28 15:32:21.927 T:4090 debug : ------ Window Deinit (DialogBusy.xml) ------ 2026-07-28 15:32:21.968 T:4117 debug : [plugin.video.youtube] player_monitor:435(onAVStarted) - Get property 'play_using': '' 2026-07-28 15:32:21.968 T:4117 debug : [plugin.video.youtube] player_monitor:439(onAVStarted) - Clear property 'busy' 2026-07-28 15:32:21.968 T:4117 debug : [plugin.video.youtube] player_monitor:441(onAVStarted) - Pop property 'player_json': '' 2026-07-28 15:32:21.996 T:4090 debug : TEMP: [framecount] video=240 ctrls=240 subs=0 skip-gui=163 skip-any=163 2026-07-28 15:32:22.008 T:4090 debug : TEMP: [framecount] video=480 ctrls=480 subs=0 skip-gui=403 skip-any=403 2026-07-28 15:32:22.020 T:4090 debug : TEMP: [framecount] video=720 ctrls=720 subs=0 skip-gui=643 skip-any=643 2026-07-28 15:32:22.022 T:4096 debug : ActiveAE::SyncStream - average error of 722.262580, start adjusting 2026-07-28 15:32:22.032 T:4090 debug : TEMP: [framecount] video=960 ctrls=960 subs=0 skip-gui=883 skip-any=883 2026-07-28 15:32:22.044 T:4090 debug : TEMP: [framecount] video=1200 ctrls=1200 subs=0 skip-gui=1123 skip-any=1123 2026-07-28 15:32:22.056 T:4090 debug : TEMP: [framecount] video=1440 ctrls=1440 subs=0 skip-gui=1363 skip-any=1363 2026-07-28 15:32:22.068 T:4090 debug : TEMP: [framecount] video=1680 ctrls=1680 subs=0 skip-gui=1603 skip-any=1603 2026-07-28 15:32:22.080 T:4090 debug : TEMP: [framecount] video=1920 ctrls=1920 subs=0 skip-gui=1843 skip-any=1843 2026-07-28 15:32:22.092 T:4090 debug : TEMP: [framecount] video=2160 ctrls=2160 subs=0 skip-gui=2083 skip-any=2083 2026-07-28 15:32:22.104 T:4090 debug : TEMP: [framecount] video=2400 ctrls=2400 subs=0 skip-gui=2323 skip-any=2323 2026-07-28 15:32:22.116 T:4090 debug : TEMP: [framecount] video=2640 ctrls=2640 subs=0 skip-gui=2563 skip-any=2563 2026-07-28 15:32:22.128 T:4090 debug : TEMP: [framecount] video=2880 ctrls=2880 subs=0 skip-gui=2803 skip-any=2803 2026-07-28 15:32:22.140 T:4090 debug : TEMP: [framecount] video=3120 ctrls=3120 subs=0 skip-gui=3043 skip-any=3043 2026-07-28 15:32:22.152 T:4090 debug : TEMP: [framecount] video=3360 ctrls=3360 subs=0 skip-gui=3283 skip-any=3283 2026-07-28 15:32:22.164 T:4090 debug : TEMP: [framecount] video=3600 ctrls=3600 subs=0 skip-gui=3523 skip-any=3523 2026-07-28 15:32:22.176 T:4090 debug : TEMP: [framecount] video=3840 ctrls=3840 subs=0 skip-gui=3763 skip-any=3763 2026-07-28 15:32:22.188 T:4090 debug : TEMP: [framecount] video=4080 ctrls=4080 subs=0 skip-gui=4003 skip-any=4003 2026-07-28 15:32:22.238 T:4090 debug : TEMP: [framecount] video=4320 ctrls=4320 subs=0 skip-gui=4241 skip-any=4241 2026-07-28 15:32:22.251 T:4090 debug : TEMP: [framecount] video=4560 ctrls=4560 subs=0 skip-gui=4481 skip-any=4481 2026-07-28 15:32:22.263 T:4090 debug : TEMP: [framecount] video=4800 ctrls=4800 subs=0 skip-gui=4721 skip-any=4721 2026-07-28 15:32:22.275 T:4090 debug : TEMP: [framecount] video=5040 ctrls=5040 subs=0 skip-gui=4961 skip-any=4961 2026-07-28 15:32:22.287 T:4090 debug : TEMP: [framecount] video=5280 ctrls=5280 subs=0 skip-gui=5201 skip-any=5201 2026-07-28 15:32:22.299 T:4090 debug : TEMP: [framecount] video=5520 ctrls=5520 subs=0 skip-gui=5441 skip-any=5441 2026-07-28 15:32:22.374 T:4090 debug : TEMP: [framecount] video=5760 ctrls=5760 subs=0 skip-gui=5678 skip-any=5678 2026-07-28 15:32:22.386 T:4090 debug : TEMP: [framecount] video=6000 ctrls=6000 subs=0 skip-gui=5918 skip-any=5918 2026-07-28 15:32:22.398 T:4090 debug : TEMP: [framecount] video=6240 ctrls=6240 subs=0 skip-gui=6158 skip-any=6158 2026-07-28 15:32:22.410 T:4090 debug : TEMP: [framecount] video=6480 ctrls=6480 subs=0 skip-gui=6398 skip-any=6398 2026-07-28 15:32:22.447 T:4446 warning : OutputPicture - timeout waiting for buffer 2026-07-28 15:32:22.456 T:4090 debug : TEMP: [framecount] video=6720 ctrls=6720 subs=0 skip-gui=6636 skip-any=6636 2026-07-28 15:32:22.468 T:4090 debug : TEMP: [framecount] video=6960 ctrls=6960 subs=0 skip-gui=6876 skip-any=6876 2026-07-28 15:32:22.480 T:4090 debug : TEMP: [framecount] video=7200 ctrls=7200 subs=0 skip-gui=7116 skip-any=7116 2026-07-28 15:32:22.492 T:4090 debug : TEMP: [framecount] video=7440 ctrls=7440 subs=0 skip-gui=7356 skip-any=7356