2022-03-21 10:20:00.299 T:2764447936 NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:05 2022-03-21 10:20:00.319 T:2972834944 DEBUG: CGUIMediaWindow::GetDirectory (smb://) 2022-03-21 10:20:00.319 T:2972834944 DEBUG: ParentPath = [smb://] 2022-03-21 10:20:00.320 T:2512515264 DEBUG: Thread waiting start, auto delete: false 2022-03-21 10:20:00.375 T:2512515264 DEBUG: Thread waiting 2512515264 terminating 2022-03-21 10:20:00.382 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting definitions 2022-03-21 10:20:00.383 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: trying to load setting definitions from old format... 2022-03-21 10:20:00.383 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting values 2022-03-21 10:20:00.387 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting definitions 2022-03-21 10:20:00.387 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: trying to load setting definitions from old format... 2022-03-21 10:20:00.387 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting values 2022-03-21 10:20:00.438 T:2639253696 DEBUG: PushCecKeypress - received key b duration 0 2022-03-21 10:20:00.541 T:2639253696 DEBUG: PushCecKeypress - received key b duration 103 2022-03-21 10:20:00.643 T:2512515264 DEBUG: Thread BackgroundLoader start, auto delete: false 2022-03-21 10:20:00.646 T:2972834944 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select 2022-03-21 10:20:00.656 T:2512515264 DEBUG: Thread BackgroundLoader 2512515264 terminating 2022-03-21 10:20:00.657 T:2972834944 DEBUG: CGUIMediaWindow::GetDirectory (smb:// .mkv/) 2022-03-21 10:20:00.657 T:2972834944 DEBUG: ParentPath = [smb://] 2022-03-21 10:20:00.657 T:2512515264 DEBUG: Thread waiting start, auto delete: false 2022-03-21 10:20:00.667 T:2512515264 DEBUG: Thread waiting 2512515264 terminating 2022-03-21 10:20:00.673 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting definitions 2022-03-21 10:20:00.673 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: trying to load setting definitions from old format... 2022-03-21 10:20:00.674 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting values 2022-03-21 10:20:00.677 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting definitions 2022-03-21 10:20:00.677 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: trying to load setting definitions from old format... 2022-03-21 10:20:00.677 T:2972834944 DEBUG: CAddonSettings[metadata.themoviedb.org]: loading setting values 2022-03-21 10:20:00.694 T:2512515264 DEBUG: Thread BackgroundLoader start, auto delete: false 2022-03-21 10:20:00.720 T:2512515264 DEBUG: Thread BackgroundLoader 2512515264 terminating 2022-03-21 10:20:01.356 T:2639253696 DEBUG: PushCecKeypress - received key b duration 0 2022-03-21 10:20:01.363 T:2972834944 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select 2022-03-21 10:20:01.363 T:2972834944 INFO: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY 2022-03-21 10:20:01.370 T:2972834944 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 2022-03-21 10:20:01.464 T:2639253696 DEBUG: PushCecKeypress - received key b duration 107 2022-03-21 10:20:01.728 T:2665668800 DEBUG: Version Check: Version installed {u'major': 18, u'tag': u'stable', u'minor': 7, u'revision': u'20200528-2c5354e70a'} 2022-03-21 10:20:01.728 T:2665668800 DEBUG: Version Check: Version available {u'major': u'19', u'extrainfo': u'final', u'tagversion': u'', u'tag': u'stable', u'addon_support': u'yes', u'minor': u'4', u'revision': u'20220302-e12e66e019'} 2022-03-21 10:20:01.728 T:2665668800 DEBUG: Version Check: You are running an older version 2022-03-21 10:20:02.238 T:2639253696 DEBUG: PushCecKeypress - received key b duration 0 2022-03-21 10:20:02.251 T:2972834944 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select 2022-03-21 10:20:02.341 T:2639253696 DEBUG: PushCecKeypress - received key b duration 103 2022-03-21 10:20:02.523 T:2972834944 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 2022-03-21 10:20:02.552 T:2972834944 DEBUG: OnPlayMedia smb:// .mkv/ .mkv.mkv 2022-03-21 10:20:02.730 T:2972834944 DEBUG: CPlayerCoreFactory::GetPlayers(smb:// .mkv/ .mkv.mkv) 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 2022-03-21 10:20:02.731 T:2972834944 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 2022-03-21 10:20:02.732 T:2972834944 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2022-03-21 10:20:02.732 T:2972834944 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2022-03-21 10:20:02.732 T:2972834944 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 2022-03-21 10:20:02.732 T:2972834944 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 2022-03-21 10:20:02.732 T:2972834944 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 2022-03-21 10:20:02.732 T:2512515264 DEBUG: Thread RefClock start, auto delete: false 2022-03-21 10:20:02.732 T:2512515264 DEBUG: CVideoReferenceClock: setting up RPi 2022-03-21 10:20:02.732 T:2512515264 DEBUG: CVideoReferenceClock: fps: 30.00 2022-03-21 10:20:02.732 T:2512515264 DEBUG: CVideoReferenceClock: Detected refreshrate: 30.000 hertz 2022-03-21 10:20:02.737 T:2972834944 DEBUG: CMMALPool::CMMALPool Created pool 0x26248c0 of size 26 x 0 for port vc.ril.video_decode:out:0(I420) 2022-03-21 10:20:02.746 T:2972834944 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 2022-03-21 10:20:02.746 T:2972834944 NOTICE: VideoPlayer::OpenFile: smb:// .mkv/ .mkv.mkv 2022-03-21 10:20:02.746 T:2972834944 DEBUG: CMMALRenderer::CMMALRenderer 2022-03-21 10:20:02.747 T:2495729856 DEBUG: Thread MMALProcess start, auto delete: false 2022-03-21 10:20:02.747 T:2972834944 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted 2022-03-21 10:20:02.747 T:2495729856 DEBUG: CMMALRenderer::Run - starting 2022-03-21 10:20:02.747 T:2487337152 DEBUG: Thread VideoPlayer start, auto delete: false 2022-03-21 10:20:02.747 T:2478944448 DEBUG: Thread BackgroundLoader start, auto delete: false 2022-03-21 10:20:02.748 T:2487337152 NOTICE: Creating InputStream 2022-03-21 10:20:02.759 T:2581844160 DEBUG: Loading settings for smb:// .mkv/ .mkv.mkv 2022-03-21 10:20:02.769 T:2487337152 DEBUG: CSMBFile::Open - opened smb://USERNAME:PASSWORD@ .mkv/ .mkv.mkv, fd=10000 2022-03-21 10:20:02.771 T:2478944448 DEBUG: Thread BackgroundLoader 2478944448 terminating 2022-03-21 10:20:02.773 T:2487337152 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 2022-03-21 10:20:02.778 T:2487337152 DEBUG: ScanForExternalSubtitles: END (total time: 6 ms) 2022-03-21 10:20:02.778 T:2487337152 NOTICE: Creating Demuxer 2022-03-21 10:20:02.785 T:2972834944 DEBUG: CVideoGUIInfo::InitCurrentItem(smb:// .mkv/ .mkv.mkv) 2022-03-21 10:20:02.785 T:2972834944 DEBUG: CPlayerGUIInfo::InitCurrentItem(smb:// .mkv/ .mkv.mkv) 2022-03-21 10:20:02.811 T:2487337152 DEBUG: Open - probing detected format [matroska,webm] 2022-03-21 10:20:02.813 T:2487337152 ERROR: ffmpeg[9441C0C0]: [matroska,webm] Read error at pos. 4784304208 (0x11d2ab050) 2022-03-21 10:20:02.813 T:2487337152 DEBUG: Open - avformat_find_stream_info starting 2022-03-21 10:20:02.885 T:2972834944 DEBUG: ------ Window Init (DialogBusy.xml) ------ 2022-03-21 10:20:02.896 T:2487337152 DEBUG: Open - av_find_stream_info finished 2022-03-21 10:20:02.896 T:2487337152 INFO: ffmpeg[9441C0C0]: Input #0, matroska,webm, from 'smb:// .mkv/ .mkv.mkv': 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Metadata: 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: encoder : libebml v1.4.2 + libmatroska v1.6.4 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: creation_time : 2022-03-18T01:55:39.000000Z 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Duration: 01:45:31.53, start: 0.000000, bitrate: 6045 kb/s 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:0: start 0.000000, end 277.416667 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:1: start 277.416667, end 603.708333 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:2: start 603.708333, end 865.708333 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:3: start 865.708333, end 1178.833333 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:4: start 1178.833333, end 1597.791667 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:5: start 1597.791667, end 1991.125000 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:6: start 1991.125000, end 2465.666667 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:7: start 2465.666667, end 2978.125000 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:8: start 2978.125000, end 3425.083333 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:9: start 3425.083333, end 3885.708333 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:10: start 3885.708333, end 4441.250000 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:11: start 4441.250000, end 4807.958333 2022-03-21 10:20:02.897 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:12: start 4807.958333, end 5171.750000 2022-03-21 10:20:02.898 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:13: start 5171.750000, end 5568.166667 2022-03-21 10:20:02.898 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:14: start 5568.166667, end 5880.833333 2022-03-21 10:20:02.898 T:2487337152 INFO: ffmpeg[9441C0C0]: Chapter #0:15: start 5880.833333, end 6331.500000 2022-03-21 10:20:02.898 T:2487337152 INFO: ffmpeg[9441C0C0]: Stream #0:0(ger): Video: h264, yuv420p(progressive), 1920x808 [SAR 1:1 DAR 240:101], 24 fps, 24 tbr, 1k tbn, 48 tbc 2022-03-21 10:20:02.898 T:2487337152 INFO: ffmpeg[9441C0C0]: Stream #0:1(ger): Audio: dts, 48000 Hz, 5.1(side), fltp, 1536 kb/s (default) 2022-03-21 10:20:02.898 T:2487337152 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 2022-03-21 10:20:02.898 T:2487337152 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 2022-03-21 10:20:02.898 T:2487337152 NOTICE: Opening stream: 0 source: 256 2022-03-21 10:20:02.898 T:2487337152 NOTICE: Whitelist search for: width: 1920, height: 808, fps: 24.000, 3D: false 2022-03-21 10:20:02.898 T:2487337152 DEBUG: Whitelist is empty using default one 2022-03-21 10:20:02.899 T:2487337152 DEBUG: Trying to find exact refresh rate 2022-03-21 10:20:02.899 T:2487337152 DEBUG: No exact whitelisted resolution matched, trying double refresh rate 2022-03-21 10:20:02.899 T:2487337152 DEBUG: No double whitelisted resolution matched, trying 3:2 pullback 2022-03-21 10:20:02.900 T:2487337152 DEBUG: No 3:2 pullback refresh rate whitelisted resolution matched, trying current resolution 2022-03-21 10:20:02.900 T:2487337152 DEBUG: Current resolution doesn't match, trying default resolution 2022-03-21 10:20:02.900 T:2487337152 DEBUG: Matched fuzzy whitelisted Resolution 3840x2160 @ 24.000000 Hz (24) 2022-03-21 10:20:02.900 T:2487337152 NOTICE: Display resolution ADJUST : 3840x2160 @ 24.000000 Hz (24) (weight: 0.000) 2022-03-21 10:20:02.919 T:2972834944 DEBUG: OnLostDevice - notify display change event 2022-03-21 10:20:03.051 T:2931814592 DEBUG: CAESinkPi:Drain delay:99ms now:0ms 2022-03-21 10:20:03.051 T:2931814592 DEBUG: CAESinkPi:Deinitialize 2022-03-21 10:20:03.053 T:2931814592 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000 2022-03-21 10:20:03.065 T:2931814592 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xae2031b8 2022-03-21 10:20:03.065 T:2972834944 NOTICE: VideoPlayer: OnLostDisplay received 2022-03-21 10:20:03.065 T:2972834944 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 2022-03-21 10:20:03.065 T:2972834944 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 2022-03-21 10:20:03.065 T:2972834944 DEBUG: Flush - flushing renderer 2022-03-21 10:20:03.065 T:2972834944 DEBUG: CMMALRenderer::Flush 2022-03-21 10:20:03.118 T:2972834944 DEBUG: CDRMUtils::SetMode - found crtc mode: 3840x2160 @ 24 Hz 2022-03-21 10:20:03.119 T:2972834944 INFO: GLES: Maximum texture width: 4096 2022-03-21 10:20:03.119 T:2972834944 NOTICE: VideoPlayer: OnResetDisplay received 2022-03-21 10:20:03.119 T:2942300352 DEBUG: CActiveAE - display reset event 2022-03-21 10:20:03.119 T:2972834944 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 2022-03-21 10:20:03.119 T:2972834944 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 2022-03-21 10:20:03.119 T:2931814592 INFO: CActiveAESink::OpenSink - initialize sink 2022-03-21 10:20:03.119 T:2931814592 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 2022-03-21 10:20:03.119 T:2931814592 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI 2022-03-21 10:20:03.120 T:2931814592 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 2022-03-21 10:20:03.121 T:2487337152 NOTICE: Creating video codec with codec id: 27 2022-03-21 10:20:03.122 T:2931814592 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae2031b8 2022-03-21 10:20:03.124 T:2931814592 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16) 2022-03-21 10:20:03.125 T:2931814592 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 2022-03-21 10:20:03.125 T:2931814592 DEBUG: Output Device : HDMI 2022-03-21 10:20:03.125 T:2931814592 DEBUG: Sample Rate : 44100 2022-03-21 10:20:03.125 T:2931814592 DEBUG: Sample Format : AE_FMT_FLOAT 2022-03-21 10:20:03.126 T:2931814592 DEBUG: Channel Count : 2 2022-03-21 10:20:03.126 T:2931814592 DEBUG: Channel Layout: FL, FR 2022-03-21 10:20:03.126 T:2931814592 DEBUG: Frames : 2205 2022-03-21 10:20:03.126 T:2931814592 DEBUG: Frame Size : 8 2022-03-21 10:20:03.129 T:2942300352 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2022-03-21 10:20:03.133 T:2972834944 DEBUG: EGL Debugging: Error: EGL_BAD_SURFACE Command: eglSwapBuffers Type: EGL_DEBUG_MSG_ERROR_KHR Message: dri2_swap_buffers 2022-03-21 10:20:03.137 T:2972834944 DEBUG: CRBP::CloseDisplay display:10000020 m_display:10000020 2022-03-21 10:20:03.140 T:2487337152 DEBUG: CMMALPool::CMMALPool Created pool 0xae217190 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV) 2022-03-21 10:20:03.156 T:2487337152 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2022-03-21 10:20:03.160 T:2487337152 DEBUG: CMMALPool::CMMALPool Created pool 0xae27a930 of size 26 x 0 for port vc.ril.video_decode:out:0(I420) 2022-03-21 10:20:03.160 T:2487337152 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x26248c0 for port vc.ril.video_decode:out:0(I420) 2022-03-21 10:20:03.160 T:2487337152 NOTICE: Creating video thread 2022-03-21 10:20:03.161 T:2504122560 DEBUG: Thread VideoPlayerVideo start, auto delete: false 2022-03-21 10:20:03.161 T:2504122560 NOTICE: running thread: video_thread 2022-03-21 10:20:03.161 T:2504122560 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0 2022-03-21 10:20:03.161 T:2487337152 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb:// .mkv/ .mkv.mkv 2022-03-21 10:20:03.162 T:2487337152 NOTICE: Opening stream: 1 source: 256 2022-03-21 10:20:03.162 T:2487337152 NOTICE: Finding audio codec for: 86020 2022-03-21 10:20:03.162 T:2487337152 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder dca 2022-03-21 10:20:03.162 T:2487337152 NOTICE: Creating audio thread 2022-03-21 10:20:03.162 T:2458902720 DEBUG: Thread VideoPlayerAudio start, auto delete: false 2022-03-21 10:20:03.162 T:2458902720 NOTICE: running thread: CVideoPlayerAudio::Process() 2022-03-21 10:20:03.163 T:2487337152 DEBUG: Prepare - Start position set to last stopped position: 1958000 2022-03-21 10:20:03.163 T:2458902720 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 2022-03-21 10:20:03.168 T:2512515264 DEBUG: CRBP::WaitVsync no vsync 531/532 display:0 delay:0 2022-03-21 10:20:03.168 T:2512515264 DEBUG: CVideoReferenceClock: cleaning up RPi 2022-03-21 10:20:03.168 T:2512515264 DEBUG: CVideoReferenceClock: setting up RPi 2022-03-21 10:20:03.168 T:2512515264 DEBUG: CVideoReferenceClock: fps: 24.00 2022-03-21 10:20:03.169 T:2512515264 DEBUG: CVideoReferenceClock: Detected refreshrate: 24.000 hertz 2022-03-21 10:20:03.219 T:2512515264 DEBUG: CRBP::WaitVsync no vsync 531/532 display:0 delay:0 2022-03-21 10:20:03.331 T:2972834944 DEBUG: Previous line repeats 2 times. 2022-03-21 10:20:03.331 T:2972834944 DEBUG: CRBP::OpenDisplay device:0 m_display:10000040 (0) 2022-03-21 10:20:11.731 T:2665668800 DEBUG: CSettingsManager: requested setting (lastnotified_version) was not found. 2022-03-21 10:20:11.732 T:2665668800 DEBUG: Version Check: Already notified one time for upgrading. 2022-03-21 10:20:11.732 T:2665668800 INFO: CPythonInvoker(0, /root/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py): script successfully run 2022-03-21 10:20:11.732 T:2665668800 DEBUG: onExecutionDone(0, /root/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py) 2022-03-21 10:20:11.847 T:2665668800 INFO: Python interpreter stopped 2022-03-21 10:20:11.847 T:2665668800 DEBUG: Thread LanguageInvoker 2665668800 terminating 2022-03-21 10:20:16.705 T:2590236864 DEBUG: CAddonSettings[script.logviewer]: loading setting definitions 2022-03-21 10:20:16.705 T:2590236864 DEBUG: CAddonSettings[script.logviewer]: trying to load setting definitions from old format... 2022-03-21 10:20:16.708 T:2590236864 DEBUG: CAddonSettings[script.logviewer]: loading setting values 2022-03-21 10:20:16.708 T:2590236864 DEBUG: [script.logviewer] Starting http server runner 2022-03-21 10:20:16.710 T:2665668800 DEBUG: [script.logviewer] Server started at port 8080 2022-03-21 10:20:16.710 T:2665668800 DEBUG: [script.logviewer] Local IP: 2022-03-21 10:20:29.400 T:2972834944 INFO: CheckIdle - Closing session to https://api.themoviedb.org (easy=0xace9ffb8, multi=0xace9dba8) 2022-03-21 10:20:32.749 T:2573451456 DEBUG: Thread JobWorker 2573451456 terminating (autodelete) 2022-03-21 10:20:32.749 T:2674061504 DEBUG: Thread JobWorker 2674061504 terminating (autodelete) 2022-03-21 10:20:32.749 T:2764447936 DEBUG: Thread JobWorker 2764447936 terminating (autodelete) 2022-03-21 10:20:32.767 T:2581844160 DEBUG: Thread JobWorker 2581844160 terminating (autodelete) 2022-03-21 10:21:03.229 T:2931814592 DEBUG: CAESinkPi:Drain delay:100ms now:0ms 2022-03-21 10:21:13.229 T:2931814592 DEBUG: CAESinkPi:Deinitialize 2022-03-21 10:21:13.229 T:2931814592 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000 2022-03-21 10:21:13.242 T:2931814592 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xae2031b8 2022-03-21 10:25:06.499 T:2487337152 DEBUG: SeekTime - seek ended up on time 1957750 2022-03-21 10:25:06.499 T:2487337152 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 2022-03-21 10:25:06.504 T:2458902720 DEBUG: CDVDAudio::Pause - pausing audio stream 2022-03-21 10:25:06.504 T:2504122560 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2022-03-21 10:25:06.504 T:2487337152 DEBUG: CVideoPlayer::SetCaching - caching state 2 2022-03-21 10:25:06.504 T:2458902720 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2022-03-21 10:25:06.504 T:2487337152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 2022-03-21 10:25:06.505 T:2487337152 DEBUG: Prepare - starting demuxer from: 1958000 2022-03-21 10:25:06.505 T:2487337152 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0 2022-03-21 10:25:06.505 T:2487337152 DEBUG: CVideoPlayer::HandleMessages - player 1 reported state: 0 2022-03-21 10:25:06.505 T:2581844160 DEBUG: Thread JobWorker start, auto delete: true 2022-03-21 10:25:06.505 T:2581844160 DEBUG: OnAVChange: CApplication::OnAVChange 2022-03-21 10:25:06.514 T:2458902720 DEBUG: CDVDAudio::Pause - pausing audio stream 2022-03-21 10:25:06.524 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957750000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.524 T:2504122560 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 2022-03-21 10:25:06.531 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957760000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.531 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957770000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.531 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957781000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.531 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957791000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.531 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957802000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.531 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957813000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.531 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957823000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957834000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957845000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957855000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957866000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957876000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957887000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957898000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957908000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.532 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957919000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.539 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957917000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CVideoPlayer::CheckContinuity - wrapback :2, prev:1957917000.000000, curr:1957750000.000000, diff:-167000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957750000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957792000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957833000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957931000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957941000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957952000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.540 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957962000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.541 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957973000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.541 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957984000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.541 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:1 dts:1957994000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.541 T:2487337152 DEBUG: CVideoPlayer::SetCaching - caching state 1 2022-03-21 10:25:06.541 T:2487337152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 2022-03-21 10:25:06.545 T:2458902720 DEBUG: CDVDAudio::Pause - pausing audio stream 2022-03-21 10:25:06.546 T:2458902720 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, no pass-through) 2022-03-21 10:25:06.546 T:2458902720 DEBUG: CVideoPlayerAudio:: synctype set to 1: resample 2022-03-21 10:25:06.546 T:2931814592 INFO: CActiveAESink::OpenSink - initialize sink 2022-03-21 10:25:06.547 T:2931814592 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 2022-03-21 10:25:06.547 T:2931814592 DEBUG: CAESinkPi:Initialize Format:24 Channels:2 Samplerate:48000 framesize:8 bufsize:19200 bytes/s=384000.00 dest=PI:HDMI 2022-03-21 10:25:06.547 T:2931814592 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 2022-03-21 10:25:06.549 T:2931814592 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae2031b8 2022-03-21 10:25:06.550 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957875000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.551 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957917000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.551 T:2931814592 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(19200), nBufferAlignment(16) 2022-03-21 10:25:06.552 T:2931814592 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 2022-03-21 10:25:06.552 T:2931814592 DEBUG: Output Device : HDMI 2022-03-21 10:25:06.552 T:2931814592 DEBUG: Sample Rate : 48000 2022-03-21 10:25:06.552 T:2931814592 DEBUG: Sample Format : AE_FMT_FLOATP 2022-03-21 10:25:06.552 T:2931814592 DEBUG: Channel Count : 2 2022-03-21 10:25:06.552 T:2931814592 DEBUG: Channel Layout: FL, FR 2022-03-21 10:25:06.552 T:2931814592 DEBUG: Frames : 2400 2022-03-21 10:25:06.553 T:2931814592 DEBUG: Frame Size : 8 2022-03-21 10:25:06.553 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:06.557 T:2942300352 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2022-03-21 10:25:06.559 T:2487337152 DEBUG: Previous line repeats 1 times. 2022-03-21 10:25:06.559 T:2487337152 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1957958000.000000 to get to start point at 1958000000.000000 2022-03-21 10:25:06.579 T:2487337152 DEBUG: CVideoPlayer::HandleMessages - player started 1 2022-03-21 10:25:06.580 T:2581844160 DEBUG: OnAVChange: CApplication::OnAVChange 2022-03-21 10:25:06.583 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:06.593 T:2504122560 DEBUG: Previous line repeats 3 times. 2022-03-21 10:25:06.594 T:2504122560 DEBUG: CRenderManager::Configure - change configuration. 1920x808. display: 1920x808. framerate: 24.00. 2022-03-21 10:25:06.609 T:2972834944 DEBUG: DeleteRenderer - deleting renderer 2022-03-21 10:25:06.610 T:2972834944 DEBUG: CMMALRenderer::~CMMALRenderer 2022-03-21 10:25:06.610 T:2972834944 DEBUG: CMMALRenderer::UnInitMMAL 2022-03-21 10:25:06.610 T:2495729856 DEBUG: CMMALRenderer::Run - stopping 2022-03-21 10:25:06.610 T:2495729856 DEBUG: Thread MMALProcess 2495729856 terminating 2022-03-21 10:25:06.610 T:2972834944 DEBUG: CMMALRenderer::CMMALRenderer 2022-03-21 10:25:06.611 T:2495729856 DEBUG: Thread MMALProcess start, auto delete: false 2022-03-21 10:25:06.611 T:2495729856 DEBUG: CMMALRenderer::Run - starting 2022-03-21 10:25:06.611 T:2972834944 DEBUG: CMMALRenderer::Configure - 1920x808->1920x808@24.00 flags:1 format:-1 orient:0 2022-03-21 10:25:06.611 T:2972834944 DEBUG: CRenderManager::Configure - 4 2022-03-21 10:25:06.614 T:2487337152 DEBUG: CVideoPlayer::HandleMessages - player started 2 2022-03-21 10:25:06.614 T:2581844160 DEBUG: OnAVChange: CApplication::OnAVChange 2022-03-21 10:25:06.614 T:2487337152 DEBUG: VideoPlayer::Sync - Audio - pts: 1958303000.000000, cache: 386383.587370, totalcache: 699999.988079 2022-03-21 10:25:06.614 T:2487337152 DEBUG: VideoPlayer::Sync - Video - pts: 1957750000.000000, cache: 50000.000000, totalcache: 100000.000000 2022-03-21 10:25:06.614 T:2487337152 DEBUG: CVideoPlayer::SetCaching - caching state 0 2022-03-21 10:25:06.614 T:2504122560 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1957650000.000000) 2022-03-21 10:25:06.614 T:2458902720 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1957650000.000000), level: 6, cache: 400978.235704 2022-03-21 10:25:06.615 T:2487337152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 2022-03-21 10:25:06.615 T:2458902720 DEBUG: CDVDAudio::Resume - resume audio stream 2022-03-21 10:25:06.615 T:2942300352 DEBUG: ActiveAE - start sync of audio stream 2022-03-21 10:25:06.616 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:06.618 T:2581844160 DEBUG: Previous line repeats 1 times. 2022-03-21 10:25:06.618 T:2581844160 DEBUG: OnAVStarted: CApplication::OnAVStarted 2022-03-21 10:25:06.624 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:06.632 T:2495729856 DEBUG: Previous line repeats 1 times. 2022-03-21 10:25:06.632 T:2495729856 DEBUG: CMMALRenderer::CheckConfigurationVout Changing Vout dimensions from 0x0 (0x0) to 1920x808 (1920x816) OPQV 2022-03-21 10:25:06.638 T:2495729856 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,808 -> 0,136,1920,944 (o:0 v:0 d:0 i:0) 2022-03-21 10:25:06.638 T:2495729856 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,808 -> 0,272,3840,1616 t:100000 2022-03-21 10:25:06.645 T:2972834944 DEBUG: CMMALRenderer::RenderUpdate - vsync -1 (+7812) 2022-03-21 10:25:06.646 T:2972834944 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 95 2022-03-21 10:25:06.688 T:2972834944 DEBUG: Activating window ID: 12005 2022-03-21 10:25:06.688 T:2972834944 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 2022-03-21 10:25:06.688 T:2972834944 DEBUG: FreeVisualisation() done 2022-03-21 10:25:06.689 T:2972834944 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 2022-03-21 10:25:06.689 T:2972834944 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 2022-03-21 10:25:06.696 T:2972834944 NOTICE: Whitelist search for: width: 1920, height: 808, fps: 24.000, 3D: false 2022-03-21 10:25:06.696 T:2972834944 DEBUG: Whitelist is empty using default one 2022-03-21 10:25:06.696 T:2972834944 DEBUG: Trying to find exact refresh rate 2022-03-21 10:25:06.696 T:2972834944 DEBUG: No exact whitelisted resolution matched, trying double refresh rate 2022-03-21 10:25:06.696 T:2972834944 DEBUG: No double whitelisted resolution matched, trying 3:2 pullback 2022-03-21 10:25:06.697 T:2972834944 DEBUG: No 3:2 pullback refresh rate whitelisted resolution matched, trying current resolution 2022-03-21 10:25:06.697 T:2972834944 DEBUG: Matched current Resolution 3840x2160 @ 24.000000 Hz (24) 2022-03-21 10:25:06.697 T:2972834944 NOTICE: Display resolution ADJUST : 3840x2160 @ 24.000000 Hz (24) (weight: -0.000) 2022-03-21 10:25:06.700 T:2495729856 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,808 -> 0,136,1920,944 (o:0 v:0 d:0 i:0) 2022-03-21 10:25:06.700 T:2581844160 DEBUG: OnAVChange: CApplication::OnAVChange 2022-03-21 10:25:06.701 T:2495729856 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,808 -> 0,272,3840,1616 t:100000 2022-03-21 10:25:06.702 T:2972834944 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 97 2022-03-21 10:25:06.706 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:06.728 T:2972834944 DEBUG: Previous line repeats 1 times. 2022-03-21 10:25:06.728 T:2972834944 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 2022-03-21 10:25:06.756 T:2942300352 DEBUG: ActiveAE::SyncStream - average error of 242.609045, start adjusting 2022-03-21 10:25:06.856 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:06.956 T:2942300352 DEBUG: Previous line repeats 2 times. 2022-03-21 10:25:06.956 T:2942300352 DEBUG: ActiveAE::SyncStream - average error 0.029908 below threshold of 30.000000 2022-03-21 10:25:06.981 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:11.439 T:2504122560 DEBUG: Previous line repeats 107 times. 2022-03-21 10:25:11.439 T:2504122560 DEBUG: CPtsTracker: detected pattern of length 1: 41666.67, frameduration: 41666.666667 2022-03-21 10:25:11.481 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:11.520 T:2639253696 DEBUG: Previous line repeats 2 times. 2022-03-21 10:25:11.524 T:2639253696 DEBUG: PushCecKeypress - received key b duration 0 2022-03-21 10:25:11.604 T:2972834944 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is OSD 2022-03-21 10:25:11.604 T:2972834944 DEBUG: ------ Window Init (VideoOSD.xml) ------ 2022-03-21 10:25:11.604 T:2972834944 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 2022-03-21 10:25:11.612 T:2639253696 DEBUG: PushCecKeypress - received key b duration 93 2022-03-21 10:25:11.631 T:2972834944 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 2022-03-21 10:25:11.687 T:2972834944 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 2022-03-21 10:25:11.691 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:11.694 T:2972834944 DEBUG: CMMALRenderer::RenderUpdate - vsync 7931 (+1) 2022-03-21 10:25:11.695 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:13.216 T:2639253696 DEBUG: Previous line repeats 37 times. 2022-03-21 10:25:13.216 T:2639253696 DEBUG: PushCecKeypress - received key b duration 0 2022-03-21 10:25:13.229 T:2972834944 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select 2022-03-21 10:25:13.232 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:13.234 T:2458902720 DEBUG: CDVDAudio::Pause - pausing audio stream 2022-03-21 10:25:13.257 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:13.373 T:2639253696 DEBUG: Previous line repeats 3 times. 2022-03-21 10:25:13.373 T:2639253696 DEBUG: PushCecKeypress - received key b duration 157 2022-03-21 10:25:13.853 T:2504122560 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 2022-03-21 10:25:13.855 T:2504122560 DEBUG: CPtsTracker: pattern lost on diff 0.000000, number of losses 1 2022-03-21 10:25:14.395 T:2504122560 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 2022-03-21 10:25:14.411 T:2478944448 DEBUG: CMMALPool::Configure pool:0xae217190 1920x808 (1920x816) pix:-1 size:128 fmt:OPQV 2022-03-21 10:25:14.897 T:2504122560 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer