2023-11-16 19:06:19.329 T:21949 info : Skipped 10 duplicate messages.. 2023-11-16 19:06:19.329 T:21949 info : Samba is idle. Closing the remaining connections 2023-11-16 19:07:00.378 T:21949 error : Control 602 in window 12901 has been asked to focus, but it can't 2023-11-16 19:07:02.667 T:21949 info : CVideoPlayer::CloseFile() 2023-11-16 19:07:02.668 T:21949 info : VideoPlayer: waiting for threads to exit 2023-11-16 19:07:02.675 T:24650 info : CVideoPlayer::OnExit() 2023-11-16 19:07:02.675 T:24650 info : Closing stream player 1 2023-11-16 19:07:02.675 T:24650 info : Waiting for audio thread to exit 2023-11-16 19:07:02.719 T:24663 info : thread end: CVideoPlayerAudio::OnExit() 2023-11-16 19:07:02.720 T:24650 info : Closing audio device 2023-11-16 19:07:02.734 T:24650 info : Deleting audio codec 2023-11-16 19:07:02.735 T:21970 info : CActiveAESink::OpenSink - initialize sink 2023-11-16 19:07:02.735 T:24650 info : Closing stream player 2 2023-11-16 19:07:02.735 T:24650 info : waiting for video thread to exit 2023-11-16 19:07:02.738 T:21970 info : Trying to open: samplerate: 44100, channelMask: 12, encoding: 4 2023-11-16 19:07:02.739 T:21970 info : Minimum size we need for stream: 11344 Bytes 2023-11-16 19:07:02.739 T:21970 info : Audiotrack buffer params are: period time = 32.154 ms, period size = 11344 bytes, num periods = 4 2023-11-16 19:07:02.739 T:21970 info : Created Audiotrackbuffer with playing time of 128.616780 ms min buffer size: 45376 bytes 2023-11-16 19:07:02.746 T:21970 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45376 m_frames: 1418 m_frameSize: 8 channels: 2 2023-11-16 19:07:02.749 T:24685 warning : OutputPicture - timeout waiting for buffer 2023-11-16 19:07:02.749 T:24685 info : thread end: video_thread 2023-11-16 19:07:02.750 T:24650 info : deleting video codec 2023-11-16 19:07:02.758 T:21949 info : VideoPlayer: finished waiting 2023-11-16 19:07:02.758 T:21949 info : CVideoPlayer::CloseFile() 2023-11-16 19:07:02.758 T:21949 info : VideoPlayer: waiting for threads to exit 2023-11-16 19:07:02.758 T:21949 info : VideoPlayer: finished waiting 2023-11-16 19:07:02.788 T:21949 info : CAndroidUtils: SetNativeResolution: 7: 1920x1080 1920x1080@59.940060 2023-11-16 19:07:02.967 T:21922 info : CAndroidUtils: Current resolution: 1920x1080 1920x1080 @ 50.000000 - Full Screen 2023-11-16 19:07:02.980 T:21949 info : GLES: Maximum texture width: 16384 2023-11-16 19:07:03.036 T:21922 info : CAndroidUtils: Current resolution: 1920x1080 1920x1080 @ 59.940060 - Full Screen 2023-11-16 19:07:03.124 T:21970 info : CActiveAESink::OpenSink - initialize sink 2023-11-16 19:07:03.124 T:21970 info : Trying to open: samplerate: 44100, channelMask: 12, encoding: 4 2023-11-16 19:07:03.130 T:21970 info : Minimum size we need for stream: 11344 Bytes 2023-11-16 19:07:03.130 T:21970 info : Audiotrack buffer params are: period time = 32.154 ms, period size = 11344 bytes, num periods = 4 2023-11-16 19:07:03.130 T:21970 info : Created Audiotrackbuffer with playing time of 128.616780 ms min buffer size: 45376 bytes 2023-11-16 19:07:03.140 T:21970 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45376 m_frames: 1418 m_frameSize: 8 channels: 2 2023-11-16 19:07:11.802 T:21949 info : Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY 2023-11-16 19:07:15.839 T:21949 info : Log level changed to "trace" 2023-11-16 19:07:15.839 T:21949 info : Enabled debug logging due to GUI setting. Level 2. 2023-11-16 19:07:15.841 T:21949 debug : ------ Window Init () ------ 2023-11-16 19:07:17.291 T:22029 debug : [service.sleepy.watchdog] user activity detected, reset idle time 2023-11-16 19:07:19.269 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 4; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:19.294 T:21949 debug : Keyboard: scancode: 0x4, sym: 0x08, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:19.294 T:21949 debug : HandleKey: backspace (0xf008) pressed, window 10016, action is Back 2023-11-16 19:07:19.305 T:21949 debug : CGUIWindowManager::PreviousWindow: Deactivate 2023-11-16 19:07:19.340 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 4; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:19.599 T:21949 debug : ------ Window Deinit (SettingsCategory.xml) ------ 2023-11-16 19:07:19.599 T:21949 debug : FreeVisualisation() done 2023-11-16 19:07:19.610 T:21949 debug : CGUIWindowManager::PreviousWindow: Activate new 2023-11-16 19:07:19.610 T:21949 debug : ------ Window Init (Settings.xml) ------ 2023-11-16 19:07:19.614 T:25879 debug : Thread LanguageInvoker start, auto delete: false 2023-11-16 19:07:19.615 T:25879 debug : initializing python engine. 2023-11-16 19:07:19.615 T:21949 debug : Keyboard: scancode: 0x4, sym: 0x08, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:19.615 T:25879 debug : CPythonInvoker(45, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper/default.py): start processing 2023-11-16 19:07:19.647 T:25879 debug : -->Python Interpreter Initialized<-- 2023-11-16 19:07:19.647 T:25879 debug : 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper/default.py): the source file to load is "/storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper/default.py" 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): full python path: 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): custom python path: 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/addons/script.module.pil/lib 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): default python path: 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python311.zip 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/site-packages 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11 2023-11-16 19:07:19.647 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11/lib-dynload 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11/site-packages 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11/site-packages/setuptools-65.5.0.post20231114-py3.11.egg 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45): adding args: 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45): default.py 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45): action=getkodisetting 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45): setting=lookandfeel.startupaction 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper/default.py): entering source directory /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper 2023-11-16 19:07:19.648 T:25879 debug : CPythonInvoker(45, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper/default.py): instantiating addon using automatically obtained id of "script.embuary.helper" dependent on version 3.0.0 of the xbmc.python api 2023-11-16 19:07:19.913 T:25879 debug : trying to load setting definitions from old format... 2023-11-16 19:07:19.916 T:25879 debug : CPythonInvoker(45, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper/default.py): script successfully run 2023-11-16 19:07:19.917 T:25879 debug : onExecutionDone(45, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.embuary.helper/default.py) 2023-11-16 19:07:19.937 T:25879 debug : . 2023-11-16 19:07:20.003 T:25879 debug : Python interpreter stopped 2023-11-16 19:07:20.003 T:25879 debug : Thread LanguageInvoker 169415572672 terminating 2023-11-16 19:07:21.871 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 4; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:21.897 T:21949 debug : Keyboard: scancode: 0x4, sym: 0x08, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:21.967 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 4; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:21.996 T:21949 debug : Keyboard: scancode: 0x4, sym: 0x08, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:21.997 T:21949 debug : HandleKey: backspace (0xf008) pressed, window 10004, action is Back 2023-11-16 19:07:21.997 T:21949 debug : CGUIWindowManager::PreviousWindow: Deactivate 2023-11-16 19:07:22.292 T:22029 debug : [service.sleepy.watchdog] user activity detected, reset idle time 2023-11-16 19:07:22.302 T:21949 debug : ------ Window Deinit (Settings.xml) ------ 2023-11-16 19:07:22.302 T:21949 debug : FreeVisualisation() done 2023-11-16 19:07:22.314 T:21949 debug : CGUIWindowManager::PreviousWindow: Activate new 2023-11-16 19:07:22.314 T:21949 debug : ------ Window Init (Home.xml) ------ 2023-11-16 19:07:22.326 T:25882 debug : Thread LanguageInvoker start, auto delete: false 2023-11-16 19:07:22.326 T:25882 debug : initializing python engine. 2023-11-16 19:07:22.326 T:25882 debug : CPythonInvoker(46, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib/entry_point.py): start processing 2023-11-16 19:07:22.360 T:25882 debug : -->Python Interpreter Initialized<-- 2023-11-16 19:07:22.360 T:25882 debug : 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib/entry_point.py): the source file to load is "/storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib/entry_point.py" 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): full python path: 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): custom python path: 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.module.simpleeval/lib 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.module.unidecode/lib 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): default python path: 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python311.zip 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/site-packages 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11/lib-dynload 2023-11-16 19:07:22.361 T:25882 debug : CPythonInvoker(46): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11/site-packages 2023-11-16 19:07:22.362 T:25882 debug : CPythonInvoker(46): /data/user/0/org.xbmc.kodi/cache/apk/assets/python3.11/lib/python3.11/site-packages/setuptools-65.5.0.post20231114-py3.11.egg 2023-11-16 19:07:22.362 T:25882 debug : CPythonInvoker(46): adding args: 2023-11-16 19:07:22.362 T:25882 debug : CPythonInvoker(46): entry_point.py 2023-11-16 19:07:22.362 T:25882 debug : CPythonInvoker(46): type=buildxml&mainmenuID=9000&group=mainmenu 2023-11-16 19:07:22.362 T:25882 debug : CPythonInvoker(46, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib/entry_point.py): entering source directory /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib 2023-11-16 19:07:22.362 T:25882 debug : CPythonInvoker(46, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib/entry_point.py): instantiating addon using automatically obtained id of "script.skinshortcuts" dependent on version 3.0.0 of the xbmc.python api 2023-11-16 19:07:22.432 T:25804 debug : DoWork - took 102 ms to load special://skin/extras/home-images/settings.jpg 2023-11-16 19:07:22.814 T:25884 debug : Thread Timer start, auto delete: false 2023-11-16 19:07:22.820 T:25882 debug : CPythonInvoker(46, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib/entry_point.py): script successfully run 2023-11-16 19:07:22.820 T:25882 debug : onExecutionDone(46, /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/script.skinshortcuts/resources/lib/entry_point.py) 2023-11-16 19:07:22.823 T:25884 debug : Thread Timer 169390865600 terminating 2023-11-16 19:07:22.824 T:25885 debug : Thread Timer start, auto delete: false 2023-11-16 19:07:22.918 T:25882 debug : Python interpreter stopped 2023-11-16 19:07:22.918 T:25882 debug : Thread LanguageInvoker 169415572672 terminating 2023-11-16 19:07:23.327 T:25885 debug : Thread Timer 169390865600 terminating 2023-11-16 19:07:23.420 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:23.432 T:21949 debug : Keyboard: scancode: 0x14, sym: 0x112, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:23.432 T:21949 debug : HandleKey: down (0xf081) pressed, window 10000, action is Down 2023-11-16 19:07:23.464 T:25804 debug : ffmpeg[0x2632ae17f0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2023-11-16 19:07:23.542 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:23.552 T:21949 debug : Keyboard: scancode: 0x14, sym: 0x112, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:24.443 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:24.465 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:24.466 T:21949 debug : HandleKey: right (0xf083) pressed, window 10000, action is Right 2023-11-16 19:07:24.593 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:24.615 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:26.071 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:26.083 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:26.115 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:26.133 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:26.134 T:21949 debug : HandleKey: return (0xf00d) pressed, window 10000, action is Select 2023-11-16 19:07:27.293 T:22029 debug : [service.sleepy.watchdog] user activity detected, reset idle time 2023-11-16 19:07:27.621 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 21; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:27.636 T:21949 debug : Keyboard: scancode: 0x15, sym: 0x114, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:27.636 T:21949 debug : HandleKey: left (0xf082) pressed, window 10000, action is Left 2023-11-16 19:07:27.666 T:25804 debug : ffmpeg[0x2632ae17f0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2023-11-16 19:07:27.691 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 21; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:27.718 T:21949 debug : Keyboard: scancode: 0x15, sym: 0x114, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:28.467 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:28.486 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:28.487 T:21949 debug : HandleKey: right (0xf083) pressed, window 10000, action is Right 2023-11-16 19:07:28.594 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:28.619 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:28.894 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:28.921 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:28.992 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:29.020 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:29.020 T:21949 debug : HandleKey: return (0xf00d) pressed, window 10000, action is Select 2023-11-16 19:07:30.017 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:30.039 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:30.040 T:21949 debug : HandleKey: right (0xf083) pressed, window 10000, action is Right 2023-11-16 19:07:30.144 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:30.171 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:30.418 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:30.439 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:30.492 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:30.505 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:30.505 T:21949 debug : HandleKey: return (0xf00d) pressed, window 10000, action is Select 2023-11-16 19:07:31.617 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 19; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:31.639 T:21949 debug : Keyboard: scancode: 0x13, sym: 0x111, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:31.639 T:21949 debug : HandleKey: up (0xf080) pressed, window 10000, action is Up 2023-11-16 19:07:31.967 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 19; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:31.990 T:21949 debug : Keyboard: scancode: 0x13, sym: 0x111, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:32.293 T:22029 debug : [service.sleepy.watchdog] user activity detected, reset idle time 2023-11-16 19:07:32.295 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 21; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:32.323 T:21949 debug : Keyboard: scancode: 0x15, sym: 0x114, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:32.323 T:21949 debug : HandleKey: left (0xf082) pressed, window 10000, action is Left 2023-11-16 19:07:32.357 T:25804 debug : ffmpeg[0x2632ae17f0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2023-11-16 19:07:32.444 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 21; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:32.460 T:21949 debug : Keyboard: scancode: 0x15, sym: 0x114, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:32.696 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:32.723 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:32.845 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:32.875 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:32.875 T:21949 debug : HandleKey: return (0xf00d) pressed, window 10000, action is Select 2023-11-16 19:07:32.875 T:21949 debug : Activating window ID: 10700 2023-11-16 19:07:33.197 T:21949 debug : ------ Window Deinit (Home.xml) ------ 2023-11-16 19:07:33.198 T:21949 debug : FreeVisualisation() done 2023-11-16 19:07:33.207 T:21949 debug : ------ Window Init (MyPVRChannels.xml) ------ 2023-11-16 19:07:33.210 T:21949 debug : CGUIMediaWindow::GetDirectory (pvr://channels/tv/Alle%20Sender@-1/) 2023-11-16 19:07:33.211 T:21949 debug : ParentPath = [pvr://channels/tv/Alle%20Sender@-1/] 2023-11-16 19:07:33.274 T:25804 debug : ffmpeg[0x2632ae17f0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2023-11-16 19:07:33.386 T:22027 debug : [script.module.pvr.artwork] Properties of PVR.Artwork cleared 2023-11-16 19:07:33.386 T:22027 debug : [script.module.pvr.artwork] collect artwork for PVR.Artwork 2023-11-16 19:07:33.388 T:22027 info : [script.module.pvr.artwork] fetch data from cache: script.module.pvr.artwork.1.0.9.charmed - zauberhafte hexen 2023-11-16 19:07:34.258 T:21949 debug : CheckIdle - Closing session to https://repo.kodinerds.net (easy=0x26c2580590, multi=0x26226d52d0) 2023-11-16 19:07:35.141 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 19; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:35.159 T:21949 debug : Keyboard: scancode: 0x13, sym: 0x111, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:35.159 T:21949 debug : HandleKey: up (0xf080) pressed, window 10700, action is Up 2023-11-16 19:07:35.174 T:25804 debug : ffmpeg[0x2632ae17f0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2023-11-16 19:07:35.266 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 19; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:35.284 T:21949 debug : Keyboard: scancode: 0x13, sym: 0x111, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:35.434 T:22027 debug : [script.module.pvr.artwork] Properties of PVR.Artwork cleared 2023-11-16 19:07:35.434 T:22027 debug : [script.module.pvr.artwork] collect artwork for PVR.Artwork 2023-11-16 19:07:35.447 T:22027 info : [script.module.pvr.artwork] fetch data from cache: script.module.pvr.artwork.1.0.9.star trek: raumschiff voyager 2023-11-16 19:07:35.492 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:35.518 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:35.641 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:35.660 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:35.660 T:21949 debug : HandleKey: return (0xf00d) pressed, window 10700, action is Select 2023-11-16 19:07:35.678 T:21949 debug : CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1786647472.pvr) 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2023-11-16 19:07:35.679 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2023-11-16 19:07:35.679 T:21949 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2023-11-16 19:07:35.679 T:21949 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2023-11-16 19:07:35.679 T:21949 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2023-11-16 19:07:35.679 T:21949 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2023-11-16 19:07:35.680 T:21949 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2023-11-16 19:07:35.682 T:21949 debug : Radio UECP (RDS) Processor - new CDVDRadioRDSData 2023-11-16 19:07:35.683 T:21949 debug : Audio ID3 tag processor - new CVideoPlayerAudioID3 2023-11-16 19:07:35.683 T:21949 info : VideoPlayer::OpenFile: pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1786647472.pvr 2023-11-16 19:07:35.683 T:25898 debug : Thread VideoPlayer start, auto delete: false 2023-11-16 19:07:35.683 T:21949 debug : OnPlayBackStarted: CApplication::OnPlayBackStarted 2023-11-16 19:07:35.683 T:25898 info : Creating InputStream 2023-11-16 19:07:35.684 T:25898 debug : AddOnLog: pvr.hts: demux open 2023-11-16 19:07:35.684 T:25898 debug : AddOnLog: pvr.hts: demux subscribe to 1786647472 2023-11-16 19:07:35.685 T:25898 debug : AddOnLog: pvr.hts: demux successfully subscribed to channel id 1786647472, subscription id 9 2023-11-16 19:07:35.686 T:25898 debug : AddOnLog: pvr.hts: demux open 2023-11-16 19:07:35.686 T:25898 debug : AddOnLog: pvr.hts: demux subscribe to 1095867802 2023-11-16 19:07:35.687 T:25804 debug : Loading settings for pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1786647472.pvr 2023-11-16 19:07:35.692 T:21949 debug : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1786647472.pvr) 2023-11-16 19:07:35.692 T:25899 debug : Thread JobWorker start, auto delete: true 2023-11-16 19:07:35.693 T:25899 debug : [threads] name: 'JobWorker' priority: '-9' 2023-11-16 19:07:35.696 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:35.696 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:35.697 T:22029 debug : [service.sleepy.watchdog] method: Info.OnChanged 2023-11-16 19:07:35.697 T:22029 debug : [service.sleepy.watchdog] data: null 2023-11-16 19:07:35.703 T:25898 debug : AddOnLog: pvr.hts: demux successfully subscribed to channel id 1095867802, subscription id 10 2023-11-16 19:07:35.703 T:25898 debug : CInputStreamPVRChannel - OpenPVRStream - opened channel stream pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1786647472.pvr 2023-11-16 19:07:35.703 T:25898 info : Creating Demuxer 2023-11-16 19:07:35.703 T:25898 debug : CVideoPlayer::SetCaching - caching state 2 2023-11-16 19:07:35.703 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-11-16 19:07:35.712 T:21949 debug : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1786647472.pvr) 2023-11-16 19:07:35.713 T:21952 debug : void CXBMCApp::OnPlayBackStarted() 2023-11-16 19:07:35.718 T:21952 debug : Visible Behind request: false 2023-11-16 19:07:35.797 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:35.797 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:35.797 T:22029 debug : [service.sleepy.watchdog] method: Info.OnChanged 2023-11-16 19:07:35.797 T:22029 debug : [service.sleepy.watchdog] data: null 2023-11-16 19:07:35.797 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:35.797 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:35.798 T:22029 debug : [service.sleepy.watchdog] method: Player.OnPlay 2023-11-16 19:07:35.798 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":61,"title":"Tele 5","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:35.813 T:21949 debug : ------ Window Init (DialogBusy.xml) ------ 2023-11-16 19:07:36.329 T:21949 debug : CheckIdle - Closing session to https://raw.githubusercontent.com (easy=0x26c25f68d0, multi=0x2622bb7550) 2023-11-16 19:07:37.293 T:22029 debug : [service.sleepy.watchdog] user activity detected, reset idle time 2023-11-16 19:07:37.293 T:22029 debug : [service.sleepy.watchdog] Idle Time has changed: 7200 2023-11-16 19:07:38.348 T:21949 debug : CheckIdle - Closing session to https://mirrors.kodi.tv (easy=0x26c2553270, multi=0x2622a67550) 2023-11-16 19:07:39.567 T:22036 debug : AddOnLog: pvr.hts: demux subscription start 2023-11-16 19:07:39.567 T:22036 debug : AddOnLog: pvr.hts: id: 1001, type H264, codec: 27 2023-11-16 19:07:39.567 T:22036 debug : AddOnLog: pvr.hts: id: 1002, type AAC, codec: 86018 2023-11-16 19:07:39.567 T:22036 debug : AddOnLog: pvr.hts: demux stream change 2023-11-16 19:07:39.568 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-11-16 19:07:39.568 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-11-16 19:07:39.568 T:25898 info : Opening stream: 1001 source: 256 2023-11-16 19:07:39.568 T:25898 info : [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false 2023-11-16 19:07:39.568 T:25898 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2023-11-16 19:07:39.569 T:25898 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 - Full Screen (18) 2023-11-16 19:07:39.569 T:25898 info : Display resolution ADJUST : 1920x1080 @ 50.000000 - Full Screen (18) (weight: 0.000) 2023-11-16 19:07:39.598 T:21949 info : CAndroidUtils: SetNativeResolution: 2: 1920x1080 1920x1080@50.000000 2023-11-16 19:07:39.601 T:25904 debug : Thread Timer start, auto delete: false 2023-11-16 19:07:39.601 T:21949 debug : CWinSystemAndroid::SetHdmiState: state: 0 2023-11-16 19:07:39.617 T:21970 debug : Draining Audio 2023-11-16 19:07:39.618 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:07:39.621 T:21949 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:39.621 T:21949 info : VideoPlayer: OnLostDisplay received 2023-11-16 19:07:39.621 T:21949 warning : CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 2023-11-16 19:07:39.621 T:21949 warning : CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 2023-11-16 19:07:39.621 T:21949 debug : Flush - flushing renderer 2023-11-16 19:07:39.771 T:21922 debug : CXBMCApp::onDisplayChanged: id: 0 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: current mode: 7: 1920x1080@59.940060 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 1: 1280x720@59.940060 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 2: 1920x1080@50.000000 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 3: 1280x720@50.000000 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 4: 1920x1080@60.000004 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 5: 1280x720@60.000004 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 6: 720x480@60.000004 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 7: 1920x1080@59.940060 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 34: 3840x2160@29.970030 2023-11-16 19:07:39.772 T:21922 debug : CAndroidUtils: available mode: 35: 3840x2160@23.976025 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 36: 1920x1080@29.970030 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 37: 1920x1080@23.976025 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 38: 3840x2160@30.000002 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 39: 3840x2160@25.000000 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 40: 3840x2160@24.000002 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 41: 1920x1080@30.000002 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 42: 1920x1080@25.000000 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 43: 1920x1080@24.000002 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 44: 3840x2160@50.000000 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 45: 3840x2160@60.000004 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: available mode: 46: 3840x2160@59.940060 2023-11-16 19:07:39.773 T:21922 info : CAndroidUtils: Current resolution: 1920x1080 1920x1080 @ 59.940060 - Full Screen 2023-11-16 19:07:39.773 T:21922 debug : CAndroidUtils: ProbeResolutions: 1920x1080 2023-11-16 19:07:39.773 T:21922 debug : virtual void CXBMCApp::onDisplayChanged(int): 2023-11-16 19:07:39.786 T:21949 info : GLES: Maximum texture width: 16384 2023-11-16 19:07:39.789 T:25898 error : OpenStream: Codec id 27 require extradata. 2023-11-16 19:07:39.789 T:25898 warning : OpenStream - Unsupported stream 1001. Stream disabled. 2023-11-16 19:07:39.789 T:25898 info : Opening stream: 1002 source: 256 2023-11-16 19:07:39.789 T:25898 info : Finding audio codec for: 86018 2023-11-16 19:07:39.789 T:25898 debug : CDVDAudioCodecAndroidMediaCodec::Open codec(86018), profile(-99), tag(0), extrasize(0) 2023-11-16 19:07:39.789 T:25898 info : CDVDAudioCodecAndroidMediaCodec: extradata required for aac decoder! 2023-11-16 19:07:39.790 T:25898 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-11-16 19:07:39.790 T:25898 info : Creating audio thread 2023-11-16 19:07:39.791 T:25913 debug : Thread VideoPlayerAudio start, auto delete: false 2023-11-16 19:07:39.791 T:25913 info : running thread: CVideoPlayerAudio::Process() 2023-11-16 19:07:39.791 T:25913 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: true 2023-11-16 19:07:39.849 T:21922 debug : CXBMCApp::onDisplayChanged: id: 0 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: current mode: 2: 1920x1080@50.000000 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 1: 1280x720@59.940060 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 2: 1920x1080@50.000000 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 3: 1280x720@50.000000 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 4: 1920x1080@60.000004 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 5: 1280x720@60.000004 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 6: 720x480@60.000004 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 7: 1920x1080@59.940060 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 34: 3840x2160@29.970030 2023-11-16 19:07:39.850 T:21922 debug : CAndroidUtils: available mode: 35: 3840x2160@23.976025 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 36: 1920x1080@29.970030 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 37: 1920x1080@23.976025 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 38: 3840x2160@30.000002 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 39: 3840x2160@25.000000 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 40: 3840x2160@24.000002 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 41: 1920x1080@30.000002 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 42: 1920x1080@25.000000 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 43: 1920x1080@24.000002 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 44: 3840x2160@50.000000 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 45: 3840x2160@60.000004 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: available mode: 46: 3840x2160@59.940060 2023-11-16 19:07:39.851 T:21922 info : CAndroidUtils: Current resolution: 1920x1080 1920x1080 @ 50.000000 - Full Screen 2023-11-16 19:07:39.851 T:21922 debug : CAndroidUtils: ProbeResolutions: 1920x1080 2023-11-16 19:07:39.852 T:21922 debug : virtual void CXBMCApp::onDisplayChanged(int): 2023-11-16 19:07:39.883 T:21949 debug : Inhibiting OS screen saver 2023-11-16 19:07:39.883 T:21949 debug : void CXBMCApp::KeepScreenOn(bool): true 2023-11-16 19:07:40.791 T:21922 debug : CXBMCApp::onReceive - Got intent. Action: android.media.action.HDMI_AUDIO_PLUG 2023-11-16 19:07:40.791 T:21922 debug : -- HDMI is plugged in: false 2023-11-16 19:07:40.791 T:21922 debug : CWinSystemAndroid::SetHdmiState: state: 0 2023-11-16 19:07:40.791 T:21922 info : VideoPlayer: OnLostDisplay received 2023-11-16 19:07:40.791 T:21922 warning : CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 2023-11-16 19:07:40.791 T:25913 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: true 2023-11-16 19:07:41.601 T:25904 debug : Thread Timer 169414532288 terminating 2023-11-16 19:07:41.791 T:21922 error : Flush - timed out waiting for renderer to flush 2023-11-16 19:07:41.792 T:21922 debug : CXBMCApp::onReceive - Got intent. Action: android.media.action.HDMI_AUDIO_PLUG 2023-11-16 19:07:41.792 T:21922 debug : -- HDMI is plugged in: true 2023-11-16 19:07:41.792 T:21922 debug : CWinSystemAndroid::SetHdmiState: state: 1 2023-11-16 19:07:41.793 T:21922 info : VideoPlayer: OnResetDisplay received 2023-11-16 19:07:41.793 T:21922 warning : CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 2023-11-16 19:07:41.793 T:21969 debug : CActiveAE - display reset event 2023-11-16 19:07:41.793 T:25913 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2023-11-16 19:07:41.793 T:21970 info : CActiveAESink::OpenSink - initialize sink 2023-11-16 19:07:41.793 T:21970 debug : CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack (IEC) 2023-11-16 19:07:41.793 T:21970 debug : CAESinkAUDIOTRACK::Initialize requested: sampleRate 44100; format: AE_FMT_FLOAT; channels: 2 2023-11-16 19:07:41.793 T:21970 debug : Updated SampleRate: 32000 Distance: 108900 2023-11-16 19:07:41.793 T:21970 debug : Updated SampleRate: 44100 Distance: 0 2023-11-16 19:07:41.793 T:21970 info : Trying to open: samplerate: 44100, channelMask: 12, encoding: 4 2023-11-16 19:07:41.793 T:21949 debug : Flush - flushing renderer 2023-11-16 19:07:41.794 T:25898 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2023-11-16 19:07:41.794 T:25804 debug : OnAVChange: CApplication::OnAVChange 2023-11-16 19:07:41.794 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2023-11-16 19:07:41.795 T:21970 info : Minimum size we need for stream: 11344 Bytes 2023-11-16 19:07:41.796 T:21970 info : Audiotrack buffer params are: period time = 32.154 ms, period size = 11344 bytes, num periods = 4 2023-11-16 19:07:41.797 T:25898 debug : GetPacketExtradata: fetching extradata, extradata_size(35) 2023-11-16 19:07:41.797 T:25898 debug : CDVDDemuxClient::ParsePacket - split extradata 2023-11-16 19:07:41.797 T:21970 info : Created Audiotrackbuffer with playing time of 128.616780 ms min buffer size: 45376 bytes 2023-11-16 19:07:41.798 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:41.798 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:41.798 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:41.798 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":61,"title":"Tele 5","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:41.823 T:21970 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45376 m_frames: 1418 m_frameSize: 8 channels: 2 2023-11-16 19:07:41.823 T:21970 debug : CActiveAESink::OpenSink - AUDIOTRACK Initialized: 2023-11-16 19:07:41.824 T:21970 debug : Output Device : AudioTrack (IEC) 2023-11-16 19:07:41.824 T:21970 debug : Sample Rate : 44100 2023-11-16 19:07:41.824 T:21970 debug : Sample Format : AE_FMT_FLOAT 2023-11-16 19:07:41.824 T:21970 debug : Channel Count : 2 2023-11-16 19:07:41.824 T:21970 debug : Channel Layout: FL, FR 2023-11-16 19:07:41.824 T:21970 debug : Frames : 1418 2023-11-16 19:07:41.824 T:21970 debug : Frame Size : 8 2023-11-16 19:07:41.824 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:07:41.841 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) profile changed from -99 to 77 2023-11-16 19:07:41.841 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) level changed from -99 to 42 2023-11-16 19:07:41.841 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) aspect changed from 0 to 1.7777777777777777 2023-11-16 19:07:41.841 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) fps changed from 1000000/20000 to 50/1 2023-11-16 19:07:41.841 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-11-16 19:07:41.841 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-11-16 19:07:41.842 T:25898 info : Opening stream: 1001 source: 256 2023-11-16 19:07:41.842 T:25898 info : [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false 2023-11-16 19:07:41.842 T:25898 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2023-11-16 19:07:41.842 T:25898 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 - Full Screen (18) 2023-11-16 19:07:41.842 T:25898 info : Display resolution ADJUST : 1920x1080 @ 50.000000 - Full Screen (18) (weight: 0.000) 2023-11-16 19:07:41.856 T:25898 info : Creating video codec with codec id: 27 2023-11-16 19:07:41.856 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Open hints: Width 1920 x Height 1080, Fpsrate 50 / Fpsscale 1, CodecID 27, Level 42, Profile 77, PTS_invalid false, Tag 0, Extradata-Size: 35 2023-11-16 19:07:41.856 T:25898 info : CBitstreamConverter::Open Invalid avcC 2023-11-16 19:07:41.857 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.Nvidia.mp2.decoder 2023-11-16 19:07:41.857 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.Nvidia.wma.decoder 2023-11-16 19:07:41.858 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.Nvidia.h264.decode 2023-11-16 19:07:41.877 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.Nvidia.h264.decode), colorFormat(2135033992) 2023-11-16 19:07:41.877 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.Nvidia.h264.decode), colorFormat(21) 2023-11-16 19:07:41.898 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::ConfigureMediaCodec Color range: AVCOL_RANGE_UNSPECIFIED 2023-11-16 19:07:41.898 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::ConfigureMediaCodec Color primaries: AVCOL_PRI_UNSPECIFIED 2023-11-16 19:07:41.898 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::ConfigureMediaCodec Transfer characteristic: AVCOL_TRC_UNSPECIFIED 2023-11-16 19:07:41.898 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::InjectExtraData 2023-11-16 19:07:41.911 T:25898 debug : CDVDVideoCodecAndroidMediaCodec:: width(1920), height(1080), stride(0), slice-height(0), color-format(0) 2023-11-16 19:07:41.911 T:25898 debug : CDVDVideoCodecAndroidMediaCodec:: crop-left(0), crop-top(0), crop-right(1919), crop-bottom(1079) 2023-11-16 19:07:41.911 T:25898 debug : CDVDVideoCodecAndroidMediaCodec:: Multi-Surface Rendering 2023-11-16 19:07:41.911 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Using codec: OMX.Nvidia.h264.decode 2023-11-16 19:07:41.911 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::UpdateFpsDuration fpsRate:50 fpsscale:1, fpsDur:20000 2023-11-16 19:07:41.911 T:25898 debug : CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2023-11-16 19:07:41.911 T:25898 info : Creating video thread 2023-11-16 19:07:41.911 T:25934 debug : Thread VideoPlayerVideo start, auto delete: false 2023-11-16 19:07:41.911 T:25934 info : running thread: video_thread 2023-11-16 19:07:41.911 T:25934 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2023-11-16 19:07:41.912 T:25898 info : Opening stream: 1002 source: 256 2023-11-16 19:07:41.912 T:25898 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2023-11-16 19:07:41.912 T:25934 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2023-11-16 19:07:41.912 T:25934 debug : CDVDVideoCodecAndroidMediaCodec::AddData current state (2) 2023-11-16 19:07:41.914 T:25898 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2023-11-16 19:07:41.915 T:25913 info : CVideoPlayerAudio: display reset occurred, checking for passthrough 2023-11-16 19:07:41.915 T:25913 debug : CDVDAudioCodecAndroidMediaCodec::Open codec(86018), profile(-99), tag(0), extrasize(0) 2023-11-16 19:07:41.916 T:25913 info : CDVDAudioCodecAndroidMediaCodec: extradata required for aac decoder! 2023-11-16 19:07:41.917 T:25913 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-11-16 19:07:41.918 T:25913 info : Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through) 2023-11-16 19:07:41.918 T:25913 debug : CVideoPlayerAudio:: synctype set to 1: resample 2023-11-16 19:07:41.931 T:25934 debug : CDVDVideoCodecAndroidMediaCodec::AddData current state (2) 2023-11-16 19:07:41.946 T:25934 debug : CDVDVideoCodecAndroidMediaCodec:: width(1920), height(1080), stride(1920), slice-height(1080), color-format(262) 2023-11-16 19:07:41.946 T:25934 debug : CDVDVideoCodecAndroidMediaCodec:: crop-left(0), crop-top(0), crop-right(1919), crop-bottom(1079) 2023-11-16 19:07:41.946 T:25934 debug : CDVDVideoCodecAndroidMediaCodec:: Multi-Surface Rendering 2023-11-16 19:07:41.947 T:25934 debug : CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 50.00. 2023-11-16 19:07:41.959 T:21970 info : CActiveAESink::OpenSink - initialize sink 2023-11-16 19:07:41.959 T:21970 debug : Draining Audio 2023-11-16 19:07:41.959 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:07:41.960 T:21949 debug : DeleteRenderer - deleting renderer 2023-11-16 19:07:41.960 T:21949 debug : LinuxRendererGLES: Cleaning up GLES resources 2023-11-16 19:07:41.962 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:07:41.962 T:21970 debug : CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack (IEC) 2023-11-16 19:07:41.962 T:21970 debug : CAESinkAUDIOTRACK::Initialize requested: sampleRate 48000; format: AE_FMT_FLOATP; channels: 2 2023-11-16 19:07:41.962 T:21970 debug : Updated SampleRate: 32000 Distance: 144000 2023-11-16 19:07:41.962 T:21970 debug : Updated SampleRate: 44100 Distance: 35100 2023-11-16 19:07:41.962 T:21970 debug : Updated SampleRate: 48000 Distance: 0 2023-11-16 19:07:41.962 T:21970 info : Trying to open: samplerate: 48000, channelMask: 12, encoding: 4 2023-11-16 19:07:41.963 T:21970 info : Minimum size we need for stream: 12320 Bytes 2023-11-16 19:07:41.963 T:21970 info : Audiotrack buffer params are: period time = 32.083 ms, period size = 12320 bytes, num periods = 4 2023-11-16 19:07:41.963 T:21970 info : Created Audiotrackbuffer with playing time of 128.333333 ms min buffer size: 49280 bytes 2023-11-16 19:07:41.969 T:21970 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 48000 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 49280 m_frames: 1540 m_frameSize: 8 channels: 2 2023-11-16 19:07:41.969 T:21970 debug : CActiveAESink::OpenSink - AUDIOTRACK Initialized: 2023-11-16 19:07:41.969 T:21970 debug : Output Device : AudioTrack (IEC) 2023-11-16 19:07:41.969 T:21970 debug : Sample Rate : 48000 2023-11-16 19:07:41.969 T:21970 debug : Sample Format : AE_FMT_FLOAT 2023-11-16 19:07:41.969 T:21970 debug : Channel Count : 2 2023-11-16 19:07:41.969 T:21970 debug : Channel Layout: FL, FR 2023-11-16 19:07:41.970 T:21970 debug : Frames : 1540 2023-11-16 19:07:41.970 T:21970 debug : Frame Size : 8 2023-11-16 19:07:41.970 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:07:41.971 T:21970 debug : Could not acquire timestamp 2023-11-16 19:07:41.971 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:07:41.974 T:21949 debug : CAndroidUtils: IsHDRDisplay: true 2023-11-16 19:07:41.975 T:21949 info : Instancing CRendererMediaCodecSurface 2023-11-16 19:07:41.975 T:21949 info : CRendererMediaCodecSurface::Configure 2023-11-16 19:07:41.975 T:21949 debug : CRenderManager::Configure - 4 2023-11-16 19:07:42.000 T:25898 debug : CVideoPlayer::HandleMessages - player started 1 2023-11-16 19:07:42.000 T:25899 debug : OnAVChange: CApplication::OnAVChange 2023-11-16 19:07:42.001 T:25898 debug : CVideoPlayer::HandleMessages - player started 2 2023-11-16 19:07:42.001 T:25899 debug : OnAVChange: CApplication::OnAVChange 2023-11-16 19:07:42.001 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-11-16 19:07:42.001 T:25898 debug : VideoPlayer::Sync - Audio - pts: 998666.000000, cache: 425374.228292, totalcache: 728333.353996 2023-11-16 19:07:42.001 T:25898 debug : VideoPlayer::Sync - Video - pts: 740000.000000, cache: 50000.000000, totalcache: 100000.000000 2023-11-16 19:07:42.001 T:25913 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-129667.353996), level: 29, cache: 396593.031292 2023-11-16 19:07:42.001 T:25913 debug : CDVDAudio::Resume - resume audio stream 2023-11-16 19:07:42.001 T:21969 debug : ActiveAE - start sync of audio stream 2023-11-16 19:07:42.001 T:25934 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-129667.353996) 2023-11-16 19:07:42.003 T:25804 debug : OnAVStarted: CApplication::OnAVStarted 2023-11-16 19:07:42.019 T:21949 debug : Activating window ID: 12005 2023-11-16 19:07:42.019 T:21949 debug : ------ Window Deinit (MyPVRChannels.xml) ------ 2023-11-16 19:07:42.019 T:21949 debug : FreeVisualisation() done 2023-11-16 19:07:42.023 T:21949 debug : ------ Window Init (VideoFullScreen.xml) ------ 2023-11-16 19:07:42.027 T:21949 info : [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false 2023-11-16 19:07:42.028 T:21949 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2023-11-16 19:07:42.028 T:21949 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 - Full Screen (18) 2023-11-16 19:07:42.028 T:21949 info : Display resolution ADJUST : 1920x1080 @ 50.000000 - Full Screen (18) (weight: 0.000) 2023-11-16 19:07:42.034 T:21949 debug : ------ Window Deinit (DialogBusy.xml) ------ 2023-11-16 19:07:42.041 T:25899 debug : OnAVChange: CApplication::OnAVChange 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":61,"title":"Tele 5","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":61,"title":"Tele 5","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVStart 2023-11-16 19:07:42.099 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":61,"title":"Tele 5","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:42.100 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:42.100 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:42.100 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:42.100 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":61,"title":"Tele 5","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:42.134 T:21969 debug : ActiveAE::SyncStream - average error of 736.559218, start adjusting 2023-11-16 19:07:42.518 T:25934 warning : OutputPicture - timeout waiting for buffer 2023-11-16 19:07:42.758 T:21969 debug : ActiveAE::SyncStream - average error 23.446064, last average error: 736.559218 2023-11-16 19:07:42.870 T:21969 debug : ActiveAE::SyncStream - average error of -24.923685, start adjusting 2023-11-16 19:07:42.870 T:21969 debug : ActiveAE::SyncStream - average error -0.007018 below threshold of 30.000000 2023-11-16 19:07:44.268 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:44.299 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:44.392 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:44.419 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:44.420 T:21949 debug : HandleKey: return (0xf00d) pressed, window 12005, action is OSD 2023-11-16 19:07:44.420 T:21949 debug : ------ Window Init (VideoOSD.xml) ------ 2023-11-16 19:07:44.431 T:21949 error : Control 602 in window 12901 has been asked to focus, but it can't 2023-11-16 19:07:44.432 T:21949 debug : ------ Window Init (DialogSeekBar.xml) ------ 2023-11-16 19:07:44.440 T:21949 debug : ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 2023-11-16 19:07:44.443 T:25804 debug : ffmpeg[0x2632ae17f0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2023-11-16 19:07:45.189 T:25934 debug : CPtsTracker: detected pattern of length 1: 20000.00, frameduration: 20000.000000 2023-11-16 19:07:45.392 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:45.403 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:45.403 T:21949 debug : HandleKey: right (0xf083) pressed, window 12901, action is Right 2023-11-16 19:07:45.518 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:45.543 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:45.614 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:45.644 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:45.644 T:21949 debug : HandleKey: right (0xf083) pressed, window 12901, action is Right 2023-11-16 19:07:45.718 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:45.743 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:46.691 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:46.724 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:46.724 T:21949 debug : HandleKey: right (0xf083) pressed, window 12901, action is Right 2023-11-16 19:07:46.791 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:46.823 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:46.892 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:46.927 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:46.928 T:21949 debug : HandleKey: right (0xf083) pressed, window 12901, action is Right 2023-11-16 19:07:46.989 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:47.022 T:21949 debug : Keyboard: scancode: 0x16, sym: 0x113, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:47.294 T:22029 debug : [service.sleepy.watchdog] user activity detected, reset idle time 2023-11-16 19:07:47.339 T:22033 debug : AddOnLog: pvr.hts: demux unsubscribe from 1095867802 2023-11-16 19:07:47.340 T:22033 debug : AddOnLog: pvr.hts: demux successfully unsubscribed from channel id 1095867802, subscription id 10 2023-11-16 19:07:47.340 T:22033 debug : AddOnLog: pvr.hts: demux close 2023-11-16 19:07:47.368 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:47.384 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:47.493 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:47.524 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:47.524 T:21949 debug : HandleKey: return (0xf00d) pressed, window 12901, action is Select 2023-11-16 19:07:47.525 T:21949 debug : Activating window ID: 10608 2023-11-16 19:07:47.525 T:21949 debug : ------ Window Init (DialogPVRChannelsOSD.xml) ------ 2023-11-16 19:07:47.525 T:21949 info : Loading skin file: DialogPVRChannelsOSD.xml, load type: LOAD_EVERY_TIME 2023-11-16 19:07:47.525 T:21949 debug : Using already stored xml root node for /storage/emulated/0/Android/data/org.xbmc.kodi/files/.kodi/addons/skin.estuary.modv2/xml/DialogPVRChannelsOSD.xml 2023-11-16 19:07:47.648 T:21949 debug : ------ Window Deinit (VideoOSD.xml) ------ 2023-11-16 19:07:47.649 T:21949 debug : ------ Window Deinit (DialogSeekBar.xml) ------ 2023-11-16 19:07:48.366 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:48.396 T:21949 debug : Keyboard: scancode: 0x14, sym: 0x112, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:48.397 T:21949 debug : HandleKey: down (0xf081) pressed, window 10608, action is Down 2023-11-16 19:07:48.408 T:25804 debug : ffmpeg[0x2632ae17f0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2023-11-16 19:07:48.462 T:22027 debug : [script.module.pvr.artwork] Properties of PVR.Artwork cleared 2023-11-16 19:07:48.462 T:22027 debug : [script.module.pvr.artwork] collect artwork for PVR.Artwork 2023-11-16 19:07:48.464 T:22027 info : [script.module.pvr.artwork] fetch data from cache: script.module.pvr.artwork.1.0.9.charmed - zauberhafte hexen 2023-11-16 19:07:48.468 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:48.498 T:21949 debug : Keyboard: scancode: 0x14, sym: 0x112, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:48.690 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:48.715 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:48.788 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 23; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:07:48.814 T:21949 debug : Keyboard: scancode: 0x17, sym: 0x0d, unicode: 0x00, modifier: 0x0 2023-11-16 19:07:48.816 T:21949 debug : HandleKey: return (0xf00d) pressed, window 10608, action is Select 2023-11-16 19:07:48.817 T:21949 debug : ------ Window Deinit (DialogPVRChannelsOSD.xml) ------ 2023-11-16 19:07:48.875 T:21949 debug : CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1095867802.pvr) 2023-11-16 19:07:48.875 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2023-11-16 19:07:48.875 T:21949 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2023-11-16 19:07:48.875 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2023-11-16 19:07:48.876 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2023-11-16 19:07:48.877 T:21949 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2023-11-16 19:07:48.877 T:21949 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2023-11-16 19:07:48.877 T:21949 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2023-11-16 19:07:48.877 T:21949 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2023-11-16 19:07:48.877 T:21949 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2023-11-16 19:07:48.877 T:21949 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2023-11-16 19:07:48.877 T:21949 info : VideoPlayer::OpenFile: pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1095867802.pvr 2023-11-16 19:07:48.891 T:25898 debug : CVideoPlayer::FlushBuffers - flushing buffers 2023-11-16 19:07:48.898 T:25934 warning : OutputPicture - timeout waiting for buffer 2023-11-16 19:07:48.898 T:25934 debug : CDVDVideoCodecAndroidMediaCodec::Reset Current state (3) 2023-11-16 19:07:48.899 T:25934 debug : CDVDVideoCodecAndroidMediaCodec::InjectExtraData 2023-11-16 19:07:48.900 T:25934 error : CMediaCodecVideoBuffer::ReleaseOutputBuffer error in render(false) 2023-11-16 19:07:48.902 T:21949 error : CMediaCodecVideoBuffer::ReleaseOutputBuffer error in render(true) 2023-11-16 19:07:48.902 T:25804 debug : OnPlayBackStarted: CApplication::OnPlayBackStarted 2023-11-16 19:07:48.910 T:25804 debug : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1095867802.pvr) 2023-11-16 19:07:48.917 T:21949 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:48.917 T:21949 debug : ------ Window Init (Custom_1135_PVROverlay.xml) ------ 2023-11-16 19:07:48.919 T:21949 debug : ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 2023-11-16 19:07:48.924 T:21949 error : CMediaCodecVideoBuffer::ReleaseOutputBuffer error in render(false) 2023-11-16 19:07:48.928 T:21952 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:48.928 T:21952 debug : void CXBMCApp::OnPlayBackStarted() 2023-11-16 19:07:48.931 T:21952 debug : Visible Behind request: false 2023-11-16 19:07:48.950 T:25913 debug : CDVDAudio::Flush - flush audio stream 2023-11-16 19:07:48.951 T:25913 debug : CDVDAudio::Pause - pausing audio stream 2023-11-16 19:07:48.951 T:25913 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2023-11-16 19:07:48.951 T:25934 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2023-11-16 19:07:48.951 T:25898 debug : CVideoPlayer::SetCaching - caching state 2 2023-11-16 19:07:48.951 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-11-16 19:07:48.951 T:25898 debug : CInputStreamPVRChannel - ClosePVRStream - closed channel stream pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1786647472.pvr 2023-11-16 19:07:48.951 T:25898 info : Creating InputStream 2023-11-16 19:07:48.951 T:25898 debug : AddOnLog: pvr.hts: demux send weight 30 2023-11-16 19:07:48.952 T:25898 debug : AddOnLog: pvr.hts: demux open 2023-11-16 19:07:48.953 T:25898 debug : AddOnLog: pvr.hts: demux subscribe to 1095867802 2023-11-16 19:07:48.954 T:25898 debug : AddOnLog: pvr.hts: demux successfully subscribed to channel id 1095867802, subscription id 11 2023-11-16 19:07:48.954 T:25898 debug : AddOnLog: pvr.hts: demux open 2023-11-16 19:07:48.954 T:25898 debug : AddOnLog: pvr.hts: demux unsubscribe from 1786647472 2023-11-16 19:07:48.955 T:21949 debug : Flush - flushing renderer 2023-11-16 19:07:48.957 T:25804 debug : Loading settings for pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1095867802.pvr 2023-11-16 19:07:48.961 T:25913 debug : CDVDAudio::Pause - pausing audio stream 2023-11-16 19:07:48.967 T:25898 debug : AddOnLog: pvr.hts: demux successfully unsubscribed from channel id 1786647472, subscription id 9 2023-11-16 19:07:48.967 T:25898 debug : AddOnLog: pvr.hts: demux subscribe to 1960327713 2023-11-16 19:07:48.968 T:25898 debug : AddOnLog: pvr.hts: demux successfully subscribed to channel id 1960327713, subscription id 12 2023-11-16 19:07:48.968 T:25898 debug : CInputStreamPVRChannel - OpenPVRStream - opened channel stream pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1095867802.pvr 2023-11-16 19:07:48.968 T:25898 info : Creating Demuxer 2023-11-16 19:07:48.969 T:25898 info : Closing stream player 2 2023-11-16 19:07:48.969 T:25898 debug : CVideoPlayer::SetCaching - caching state 0 2023-11-16 19:07:48.969 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-11-16 19:07:48.969 T:25898 info : waiting for video thread to exit 2023-11-16 19:07:48.969 T:25934 info : thread end: video_thread 2023-11-16 19:07:48.969 T:25934 debug : Thread VideoPlayerVideo 171472829632 terminating 2023-11-16 19:07:48.969 T:25898 info : deleting video codec 2023-11-16 19:07:48.969 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Dispose 2023-11-16 19:07:48.969 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::SignalEndOfStream: state: 2 2023-11-16 19:07:48.969 T:25898 debug : CMediaCodecVideoBufferPool::~CMediaCodecVideoBufferPool Releasing 5 buffers 2023-11-16 19:07:48.985 T:25898 info : Closing stream player 1 2023-11-16 19:07:48.985 T:25898 info : Waiting for audio thread to exit 2023-11-16 19:07:48.991 T:25913 info : thread end: CVideoPlayerAudio::OnExit() 2023-11-16 19:07:48.991 T:25913 debug : Thread VideoPlayerAudio 171276405952 terminating 2023-11-16 19:07:48.992 T:25898 info : Closing audio device 2023-11-16 19:07:48.992 T:25898 debug : CDVDAudio::Flush - flush audio stream 2023-11-16 19:07:48.992 T:21969 debug : CActiveAE::DiscardStream - audio stream deleted 2023-11-16 19:07:48.992 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:07:48.992 T:25898 info : Skipped 2 duplicate messages.. 2023-11-16 19:07:48.992 T:25898 info : Deleting audio codec 2023-11-16 19:07:48.992 T:25898 debug : CVideoPlayer::SetCaching - caching state 2 2023-11-16 19:07:48.992 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-11-16 19:07:48.997 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] method: Info.OnChanged 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] data: null 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] method: Info.OnChanged 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] data: null 2023-11-16 19:07:48.998 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:48.999 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:48.999 T:22029 debug : [service.sleepy.watchdog] method: Player.OnPlay 2023-11-16 19:07:48.999 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":36,"title":"sixx HD","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:49.206 T:21970 info : CActiveAESink::OpenSink - initialize sink 2023-11-16 19:07:49.206 T:21970 debug : Draining Audio 2023-11-16 19:07:49.207 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:07:49.209 T:21970 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:49.209 T:21970 debug : CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack (IEC) 2023-11-16 19:07:49.209 T:21970 debug : CAESinkAUDIOTRACK::Initialize requested: sampleRate 44100; format: AE_FMT_FLOAT; channels: 2 2023-11-16 19:07:49.209 T:21970 debug : Updated SampleRate: 32000 Distance: 108900 2023-11-16 19:07:49.209 T:21970 debug : Updated SampleRate: 44100 Distance: 0 2023-11-16 19:07:49.209 T:21970 info : Trying to open: samplerate: 44100, channelMask: 12, encoding: 4 2023-11-16 19:07:49.210 T:21970 info : Minimum size we need for stream: 11344 Bytes 2023-11-16 19:07:49.211 T:21970 info : Audiotrack buffer params are: period time = 32.154 ms, period size = 11344 bytes, num periods = 4 2023-11-16 19:07:49.211 T:21970 info : Created Audiotrackbuffer with playing time of 128.616780 ms min buffer size: 45376 bytes 2023-11-16 19:07:49.218 T:21970 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45376 m_frames: 1418 m_frameSize: 8 channels: 2 2023-11-16 19:07:49.219 T:21970 debug : CActiveAESink::OpenSink - AUDIOTRACK Initialized: 2023-11-16 19:07:49.219 T:21970 debug : Output Device : AudioTrack (IEC) 2023-11-16 19:07:49.219 T:21970 debug : Sample Rate : 44100 2023-11-16 19:07:49.219 T:21970 debug : Sample Format : AE_FMT_FLOAT 2023-11-16 19:07:49.219 T:21970 debug : Channel Count : 2 2023-11-16 19:07:49.219 T:21970 debug : Channel Layout: FL, FR 2023-11-16 19:07:49.219 T:21970 debug : Frames : 1418 2023-11-16 19:07:49.219 T:21970 debug : Frame Size : 8 2023-11-16 19:07:49.219 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:07:49.220 T:21970 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:49.220 T:21970 debug : Could not acquire timestamp 2023-11-16 19:07:52.294 T:22029 debug : [service.sleepy.watchdog] user activity detected, reset idle time 2023-11-16 19:07:52.861 T:22036 debug : AddOnLog: pvr.hts: demux subscription start 2023-11-16 19:07:52.861 T:22036 debug : AddOnLog: pvr.hts: id: 1001, type H264, codec: 27 2023-11-16 19:07:52.862 T:22036 debug : AddOnLog: pvr.hts: id: 1002, type AAC, codec: 86018 2023-11-16 19:07:52.862 T:22036 debug : AddOnLog: pvr.hts: demux stream change 2023-11-16 19:07:52.862 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-11-16 19:07:52.862 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-11-16 19:07:52.863 T:25898 info : Opening stream: 1001 source: 256 2023-11-16 19:07:52.863 T:25898 info : [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false 2023-11-16 19:07:52.863 T:25898 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2023-11-16 19:07:52.863 T:25898 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 - Full Screen (18) 2023-11-16 19:07:52.864 T:25898 info : Display resolution ADJUST : 1920x1080 @ 50.000000 - Full Screen (18) (weight: 0.000) 2023-11-16 19:07:52.877 T:25898 error : OpenStream: Codec id 27 require extradata. 2023-11-16 19:07:52.877 T:25898 warning : OpenStream - Unsupported stream 1001. Stream disabled. 2023-11-16 19:07:52.877 T:25898 info : Opening stream: 1002 source: 256 2023-11-16 19:07:52.877 T:25898 info : Finding audio codec for: 86018 2023-11-16 19:07:52.877 T:25898 debug : CDVDAudioCodecAndroidMediaCodec::Open codec(86018), profile(-99), tag(0), extrasize(0) 2023-11-16 19:07:52.877 T:25898 info : CDVDAudioCodecAndroidMediaCodec: extradata required for aac decoder! 2023-11-16 19:07:52.879 T:25898 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-11-16 19:07:52.880 T:25898 info : Creating audio thread 2023-11-16 19:07:52.880 T:25949 debug : Thread VideoPlayerAudio start, auto delete: false 2023-11-16 19:07:52.880 T:21949 info : [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false 2023-11-16 19:07:52.880 T:25949 info : running thread: CVideoPlayerAudio::Process() 2023-11-16 19:07:52.880 T:25949 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2023-11-16 19:07:52.880 T:21949 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2023-11-16 19:07:52.880 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2023-11-16 19:07:52.880 T:25804 debug : OnAVChange: CApplication::OnAVChange 2023-11-16 19:07:52.881 T:21949 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 - Full Screen (18) 2023-11-16 19:07:52.881 T:21949 info : Display resolution ADJUST : 1920x1080 @ 50.000000 - Full Screen (18) (weight: 0.000) 2023-11-16 19:07:52.881 T:25898 debug : GetPacketExtradata: fetching extradata, extradata_size(35) 2023-11-16 19:07:52.882 T:25898 debug : CDVDDemuxClient::ParsePacket - split extradata 2023-11-16 19:07:52.897 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:52.897 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:52.897 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:52.897 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":36,"title":"sixx HD","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:52.918 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) profile changed from -99 to 77 2023-11-16 19:07:52.918 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) level changed from -99 to 42 2023-11-16 19:07:52.918 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) aspect changed from 0 to 1.7777777777777777 2023-11-16 19:07:52.918 T:25898 debug : CDVDDemuxClient::ParsePacket - (1001) fps changed from 1000000/20000 to 50/1 2023-11-16 19:07:52.918 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27 2023-11-16 19:07:52.918 T:25898 debug : CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018 2023-11-16 19:07:52.918 T:25898 info : Opening stream: 1001 source: 256 2023-11-16 19:07:52.918 T:25898 info : [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false 2023-11-16 19:07:52.918 T:25898 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2023-11-16 19:07:52.918 T:25898 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 - Full Screen (18) 2023-11-16 19:07:52.919 T:25898 info : Display resolution ADJUST : 1920x1080 @ 50.000000 - Full Screen (18) (weight: 0.000) 2023-11-16 19:07:52.935 T:25898 info : Creating video codec with codec id: 27 2023-11-16 19:07:52.935 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Open hints: Width 1920 x Height 1080, Fpsrate 50 / Fpsscale 1, CodecID 27, Level 42, Profile 77, PTS_invalid false, Tag 0, Extradata-Size: 35 2023-11-16 19:07:52.935 T:25898 info : CBitstreamConverter::Open Invalid avcC 2023-11-16 19:07:52.936 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.Nvidia.mp2.decoder 2023-11-16 19:07:52.936 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.Nvidia.wma.decoder 2023-11-16 19:07:52.937 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.Nvidia.h264.decode 2023-11-16 19:07:52.940 T:21949 info : [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false 2023-11-16 19:07:52.940 T:21949 debug : [WHITELIST] Searching for an exact resolution with an exact refresh rate 2023-11-16 19:07:52.940 T:21949 debug : [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 50.000000 - Full Screen (18) 2023-11-16 19:07:52.941 T:21949 info : Display resolution ADJUST : 1920x1080 @ 50.000000 - Full Screen (18) (weight: 0.000) 2023-11-16 19:07:52.950 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.Nvidia.h264.decode), colorFormat(2135033992) 2023-11-16 19:07:52.950 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.Nvidia.h264.decode), colorFormat(21) 2023-11-16 19:07:52.957 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::ConfigureMediaCodec Color range: AVCOL_RANGE_UNSPECIFIED 2023-11-16 19:07:52.957 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::ConfigureMediaCodec Color primaries: AVCOL_PRI_UNSPECIFIED 2023-11-16 19:07:52.957 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::ConfigureMediaCodec Transfer characteristic: AVCOL_TRC_UNSPECIFIED 2023-11-16 19:07:52.957 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::InjectExtraData 2023-11-16 19:07:52.970 T:25898 debug : CDVDVideoCodecAndroidMediaCodec:: width(1920), height(1080), stride(0), slice-height(0), color-format(0) 2023-11-16 19:07:52.970 T:25898 debug : CDVDVideoCodecAndroidMediaCodec:: crop-left(0), crop-top(0), crop-right(1919), crop-bottom(1079) 2023-11-16 19:07:52.970 T:25898 debug : CDVDVideoCodecAndroidMediaCodec:: Multi-Surface Rendering 2023-11-16 19:07:52.970 T:25898 info : CDVDVideoCodecAndroidMediaCodec::Open Using codec: OMX.Nvidia.h264.decode 2023-11-16 19:07:52.970 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::UpdateFpsDuration fpsRate:50 fpsscale:1, fpsDur:20000 2023-11-16 19:07:52.970 T:25898 debug : CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2023-11-16 19:07:52.970 T:25898 info : Creating video thread 2023-11-16 19:07:52.970 T:25957 debug : Thread VideoPlayerVideo start, auto delete: false 2023-11-16 19:07:52.970 T:25957 info : running thread: video_thread 2023-11-16 19:07:52.971 T:25957 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2023-11-16 19:07:52.971 T:25898 info : Opening stream: 1002 source: 256 2023-11-16 19:07:52.971 T:25898 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2023-11-16 19:07:52.971 T:25898 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2023-11-16 19:07:52.971 T:25899 debug : OnAVChange: CApplication::OnAVChange 2023-11-16 19:07:52.971 T:25957 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:52.971 T:25957 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2023-11-16 19:07:52.971 T:25957 debug : CDVDVideoCodecAndroidMediaCodec::AddData current state (2) 2023-11-16 19:07:52.971 T:25898 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2023-11-16 19:07:52.971 T:25949 debug : CVideoPlayerAudio: stream props changed, checking for passthrough 2023-11-16 19:07:52.972 T:25949 debug : CDVDAudioCodecAndroidMediaCodec::Open codec(86018), profile(-99), tag(0), extrasize(0) 2023-11-16 19:07:52.972 T:25949 info : CDVDAudioCodecAndroidMediaCodec: extradata required for aac decoder! 2023-11-16 19:07:52.973 T:25949 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-11-16 19:07:52.973 T:25949 info : Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through) 2023-11-16 19:07:52.973 T:25949 debug : CVideoPlayerAudio:: synctype set to 1: resample 2023-11-16 19:07:52.983 T:25957 debug : CDVDVideoCodecAndroidMediaCodec::AddData current state (2) 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":36,"title":"sixx HD","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:52.998 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":36,"title":"sixx HD","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:53.000 T:25957 debug : CDVDVideoCodecAndroidMediaCodec:: width(1920), height(1080), stride(1920), slice-height(1080), color-format(262) 2023-11-16 19:07:53.001 T:25957 debug : CDVDVideoCodecAndroidMediaCodec:: crop-left(0), crop-top(0), crop-right(1919), crop-bottom(1079) 2023-11-16 19:07:53.001 T:25957 debug : CDVDVideoCodecAndroidMediaCodec:: Multi-Surface Rendering 2023-11-16 19:07:53.056 T:25898 debug : CVideoPlayer::HandleMessages - player started 2 2023-11-16 19:07:53.062 T:21970 info : CActiveAESink::OpenSink - initialize sink 2023-11-16 19:07:53.062 T:21970 debug : Draining Audio 2023-11-16 19:07:53.063 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:07:53.065 T:21970 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:53.065 T:21970 debug : CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack (IEC) 2023-11-16 19:07:53.065 T:21970 debug : CAESinkAUDIOTRACK::Initialize requested: sampleRate 48000; format: AE_FMT_FLOATP; channels: 2 2023-11-16 19:07:53.065 T:21970 debug : Updated SampleRate: 32000 Distance: 144000 2023-11-16 19:07:53.066 T:21970 debug : Updated SampleRate: 44100 Distance: 35100 2023-11-16 19:07:53.066 T:21970 debug : Updated SampleRate: 48000 Distance: 0 2023-11-16 19:07:53.066 T:21970 info : Trying to open: samplerate: 48000, channelMask: 12, encoding: 4 2023-11-16 19:07:53.067 T:21970 info : Minimum size we need for stream: 12320 Bytes 2023-11-16 19:07:53.067 T:21970 info : Audiotrack buffer params are: period time = 32.083 ms, period size = 12320 bytes, num periods = 4 2023-11-16 19:07:53.067 T:21970 info : Created Audiotrackbuffer with playing time of 128.333333 ms min buffer size: 49280 bytes 2023-11-16 19:07:53.073 T:21970 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 48000 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 49280 m_frames: 1540 m_frameSize: 8 channels: 2 2023-11-16 19:07:53.073 T:21970 debug : CActiveAESink::OpenSink - AUDIOTRACK Initialized: 2023-11-16 19:07:53.073 T:21970 debug : Output Device : AudioTrack (IEC) 2023-11-16 19:07:53.073 T:21970 debug : Sample Rate : 48000 2023-11-16 19:07:53.073 T:21970 debug : Sample Format : AE_FMT_FLOAT 2023-11-16 19:07:53.073 T:21970 debug : Channel Count : 2 2023-11-16 19:07:53.073 T:21970 debug : Channel Layout: FL, FR 2023-11-16 19:07:53.073 T:21970 debug : Frames : 1540 2023-11-16 19:07:53.073 T:21970 debug : Frame Size : 8 2023-11-16 19:07:53.076 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:07:53.097 T:25898 info : Skipped 1 duplicate messages.. 2023-11-16 19:07:53.096 T:25898 debug : CVideoPlayer::HandleMessages - player started 1 2023-11-16 19:07:53.097 T:25899 debug : OnAVChange: CApplication::OnAVChange 2023-11-16 19:07:53.097 T:25898 debug : VideoPlayer::Sync - Audio - pts: 998666.000000, cache: 426151.676292, totalcache: 728333.353996 2023-11-16 19:07:53.097 T:25898 debug : VideoPlayer::Sync - Video - pts: 740000.000000, cache: 50000.000000, totalcache: 100000.000000 2023-11-16 19:07:53.097 T:25949 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-129667.353996), level: 1, cache: 408088.395292 2023-11-16 19:07:53.097 T:25957 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-129667.353996) 2023-11-16 19:07:53.097 T:25949 debug : CDVDAudio::Resume - resume audio stream 2023-11-16 19:07:53.097 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2023-11-16 19:07:53.097 T:25804 debug : OnAVStarted: CApplication::OnAVStarted 2023-11-16 19:07:53.098 T:21969 debug : ActiveAE - start sync of audio stream 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVChange 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":36,"title":"sixx HD","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] method: Player.OnAVStart 2023-11-16 19:07:53.099 T:22029 debug : [service.sleepy.watchdog] data: {"item":{"channeltype":"tv","id":36,"title":"sixx HD","type":"channel"},"player":{"playerid":1,"speed":1}} 2023-11-16 19:07:53.239 T:21969 debug : ActiveAE::SyncStream - average error of 729.030217, start adjusting 2023-11-16 19:07:53.619 T:25957 warning : OutputPicture - timeout waiting for buffer 2023-11-16 19:07:53.879 T:21969 debug : ActiveAE::SyncStream - average error 21.509924 below threshold of 30.000000 2023-11-16 19:07:56.440 T:25957 debug : CPtsTracker: detected pattern of length 1: 20000.00, frameduration: 20000.000000 2023-11-16 19:07:59.341 T:22033 debug : AddOnLog: pvr.hts: demux unsubscribe from 1960327713 2023-11-16 19:07:59.342 T:22033 debug : AddOnLog: pvr.hts: demux successfully unsubscribed from channel id 1960327713, subscription id 12 2023-11-16 19:07:59.342 T:22033 debug : AddOnLog: pvr.hts: demux close 2023-11-16 19:07:59.424 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-11-16 19:08:01.062 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2023-11-16 19:08:01.421 T:25898 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-11-16 19:08:01.619 T:21948 debug : CAndroidKey: key down (dev: 5; src: 769; code: 4; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:08:01.635 T:21949 debug : Keyboard: scancode: 0x4, sym: 0x08, unicode: 0x00, modifier: 0x0 2023-11-16 19:08:01.743 T:21948 debug : CAndroidKey: key up (dev: 5; src: 769; code: 4; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no) 2023-11-16 19:08:01.755 T:21949 debug : Keyboard: scancode: 0x4, sym: 0x08, unicode: 0x00, modifier: 0x0 2023-11-16 19:08:01.756 T:21949 debug : HandleKey: backspace (0xf008) pressed, window 12005, action is Stop 2023-11-16 19:08:01.756 T:21949 info : CVideoPlayer::CloseFile() 2023-11-16 19:08:01.756 T:21949 debug : DeleteRenderer - deleting renderer 2023-11-16 19:08:01.757 T:21949 info : VideoPlayer: waiting for threads to exit 2023-11-16 19:08:01.778 T:25898 info : CVideoPlayer::OnExit() 2023-11-16 19:08:01.778 T:25898 info : Closing stream player 1 2023-11-16 19:08:01.778 T:25898 info : Waiting for audio thread to exit 2023-11-16 19:08:01.815 T:25949 info : thread end: CVideoPlayerAudio::OnExit() 2023-11-16 19:08:01.815 T:25949 debug : Thread VideoPlayerAudio 170829294784 terminating 2023-11-16 19:08:01.815 T:25898 info : Closing audio device 2023-11-16 19:08:01.894 T:25898 debug : CDVDAudio::Flush - flush audio stream 2023-11-16 19:08:01.894 T:21969 debug : CActiveAE::DiscardStream - audio stream deleted 2023-11-16 19:08:01.894 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:08:01.895 T:25898 info : Skipped 2 duplicate messages.. 2023-11-16 19:08:01.895 T:25898 info : Deleting audio codec 2023-11-16 19:08:01.896 T:25898 info : Closing stream player 2 2023-11-16 19:08:01.897 T:25898 info : waiting for video thread to exit 2023-11-16 19:08:01.900 T:25957 warning : OutputPicture - timeout waiting for buffer 2023-11-16 19:08:01.900 T:25957 info : thread end: video_thread 2023-11-16 19:08:01.900 T:25957 debug : Thread VideoPlayerVideo 171361115328 terminating 2023-11-16 19:08:01.900 T:21970 info : CActiveAESink::OpenSink - initialize sink 2023-11-16 19:08:01.901 T:21970 debug : Draining Audio 2023-11-16 19:08:01.901 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:08:01.902 T:25898 info : deleting video codec 2023-11-16 19:08:01.902 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::Dispose 2023-11-16 19:08:01.903 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::SignalEndOfStream: state: 3 2023-11-16 19:08:01.903 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::SignalEndOfStream: ReleaseMediaCodecBuffers 2023-11-16 19:08:01.903 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:08:01.903 T:21970 debug : CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack (IEC) 2023-11-16 19:08:01.903 T:21970 debug : CAESinkAUDIOTRACK::Initialize requested: sampleRate 44100; format: AE_FMT_FLOAT; channels: 2 2023-11-16 19:08:01.904 T:21970 debug : Updated SampleRate: 32000 Distance: 108900 2023-11-16 19:08:01.904 T:21970 debug : Updated SampleRate: 44100 Distance: 0 2023-11-16 19:08:01.904 T:21970 info : Trying to open: samplerate: 44100, channelMask: 12, encoding: 4 2023-11-16 19:08:01.903 T:25898 debug : CDVDVideoCodecAndroidMediaCodec::SignalEndOfStream: BUFFER_FLAG_END_OF_STREAM send 2023-11-16 19:08:01.905 T:21970 info : Minimum size we need for stream: 11344 Bytes 2023-11-16 19:08:01.905 T:21970 info : Audiotrack buffer params are: period time = 32.154 ms, period size = 11344 bytes, num periods = 4 2023-11-16 19:08:01.905 T:21970 info : Created Audiotrackbuffer with playing time of 128.616780 ms min buffer size: 45376 bytes 2023-11-16 19:08:01.912 T:25898 debug : CMediaCodecVideoBufferPool::~CMediaCodecVideoBufferPool Releasing 5 buffers 2023-11-16 19:08:01.913 T:25898 debug : CInputStreamPVRChannel - ClosePVRStream - closed channel stream pvr://channels/tv/Alle%20Sender@-1/1@pvr.hts_1095867802.pvr 2023-11-16 19:08:01.913 T:25898 debug : Thread VideoPlayer 171266321600 terminating 2023-11-16 19:08:01.913 T:21970 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 45376 m_frames: 1418 m_frameSize: 8 channels: 2 2023-11-16 19:08:01.914 T:21970 debug : CActiveAESink::OpenSink - AUDIOTRACK Initialized: 2023-11-16 19:08:01.914 T:21970 debug : Output Device : AudioTrack (IEC) 2023-11-16 19:08:01.914 T:21970 debug : Sample Rate : 44100 2023-11-16 19:08:01.914 T:21970 debug : Sample Format : AE_FMT_FLOAT 2023-11-16 19:08:01.914 T:21970 debug : Channel Count : 2 2023-11-16 19:08:01.914 T:21970 debug : Channel Layout: FL, FR 2023-11-16 19:08:01.914 T:21970 debug : Frames : 1418 2023-11-16 19:08:01.914 T:21970 debug : Frame Size : 8 2023-11-16 19:08:01.914 T:21969 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2023-11-16 19:08:01.920 T:25899 info : Skipped 1 duplicate messages.. 2023-11-16 19:08:01.920 T:25899 debug : OnPlayBackStopped: CApplication::OnPlayBackStopped 2023-11-16 19:08:01.921 T:21949 info : VideoPlayer: finished waiting 2023-11-16 19:08:01.921 T:21949 info : CVideoPlayer::CloseFile() 2023-11-16 19:08:01.921 T:21949 info : VideoPlayer: waiting for threads to exit 2023-11-16 19:08:01.921 T:21949 info : VideoPlayer: finished waiting 2023-11-16 19:08:01.923 T:21949 debug : Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 2023-11-16 19:08:01.923 T:21949 debug : Audio ID3 tag processor - delete ~CVideoPlayerAudioID3 2023-11-16 19:08:01.926 T:21952 debug : void CXBMCApp::OnPlayBackStopped() 2023-11-16 19:08:01.933 T:21949 debug : CGUIWindowManager::PreviousWindow: Deactivate 2023-11-16 19:08:01.933 T:21949 debug : ------ Window Deinit (VideoFullScreen.xml) ------ 2023-11-16 19:08:01.949 T:21949 info : CAndroidUtils: SetNativeResolution: 7: 1920x1080 1920x1080@59.940060 2023-11-16 19:08:01.950 T:25979 debug : Thread Timer start, auto delete: false 2023-11-16 19:08:01.951 T:21949 debug : CWinSystemAndroid::SetHdmiState: state: 0 2023-11-16 19:08:01.990 T:21970 debug : Draining Audio 2023-11-16 19:08:01.990 T:21970 debug : CAESinkAUDIOTRACK::Deinitialize 2023-11-16 19:08:02.001 T:22029 info : Skipped 1 duplicate messages.. 2023-11-16 19:08:02.001 T:22029 debug : [service.sleepy.watchdog] Notification triggered 2023-11-16 19:08:02.002 T:22029 debug : [service.sleepy.watchdog] sender: xbmc 2023-11-16 19:08:02.002 T:22029 debug : [service.sleepy.watchdog] method: Player.OnStop 2023-11-16 19:08:02.002 T:22029 debug : [service.sleepy.watchdog] data: {"end":false,"item":{"channeltype":"tv","id":36,"title":"sixx HD","type":"channel"}} 2023-11-16 19:08:02.123 T:21922 debug : CXBMCApp::onDisplayChanged: id: 0 2023-11-16 19:08:02.123 T:21922 debug : CAndroidUtils: current mode: 2: 1920x1080@50.000000 2023-11-16 19:08:02.123 T:21922 debug : CAndroidUtils: available mode: 1: 1280x720@59.940060 2023-11-16 19:08:02.123 T:21922 debug : CAndroidUtils: available mode: 2: 1920x1080@50.000000