· 6 years ago · Nov 09, 2019, 04:26 PM
12019-11-09 16:44:27.836 T:1915319856 INFO: UDisks: Added /media/MUSIC
22019-11-09 16:44:27.836 T:1915319856 DEBUG: ------ Window Init (DialogNotification.xml) ------
32019-11-09 16:44:28.852 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
42019-11-09 16:44:28.852 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
52019-11-09 16:44:28.858 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
62019-11-09 16:44:32.919 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
72019-11-09 16:44:32.920 T:1766748896 DEBUG: Thread Timer start, auto delete: false
82019-11-09 16:44:32.943 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
92019-11-09 16:44:33.041 T:1766748896 DEBUG: Thread Timer 1766748896 terminating
102019-11-09 16:44:33.059 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
112019-11-09 16:44:33.060 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
122019-11-09 16:44:33.061 T:1915319856 DEBUG: Activating window ID: 10002
132019-11-09 16:44:33.417 T:1915319856 DEBUG: ------ Window Deinit (Home.xml) ------
142019-11-09 16:44:33.417 T:1915319856 DEBUG: FreeVisualisation() done
152019-11-09 16:44:33.467 T:1915319856 DEBUG: ------ Window Init (MyPics.xml) ------
162019-11-09 16:44:33.472 T:1915319856 ERROR: Control 55 in window 10002 has been asked to focus, but it can't
172019-11-09 16:44:33.524 T:1915319856 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fsections%2f3%2fall&mode=browseplex)
182019-11-09 16:44:33.524 T:1915319856 DEBUG: ParentPath = [plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fsections%2f3%2fall&mode=browseplex]
192019-11-09 16:44:33.540 T:1915319856 DEBUG: Loading items: 103, directory: plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fsections%2f3%2fall&mode=browseplex sort method: 0, ascending: false
202019-11-09 16:44:33.541 T:1332511456 DEBUG: CAddonDatabase::SetLastUsed[plugin.video.plexkodiconnect] took 16 ms
212019-11-09 16:44:33.541 T:1766748896 DEBUG: Thread JobWorker start, auto delete: true
222019-11-09 16:44:33.563 T:1481614048 DEBUG: Thread BackgroundLoader start, auto delete: false
232019-11-09 16:44:33.575 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x54e2dca8
242019-11-09 16:44:33.576 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(81920), nBufferAlignment(16)
252019-11-09 16:44:33.578 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x5c979a38
262019-11-09 16:44:33.579 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(81920), nBufferAlignment(16)
272019-11-09 16:44:33.579 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x6d527ed8
282019-11-09 16:44:33.580 T:1332511456 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
292019-11-09 16:44:33.588 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x5ea84ce8
302019-11-09 16:44:33.588 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x54e2dca8
312019-11-09 16:44:33.589 T:1766748896 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
322019-11-09 16:44:33.590 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x6d527ed8
332019-11-09 16:44:33.590 T:1332511456 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/1/119b96ac.jpg 683x512
342019-11-09 16:44:33.595 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x5c979a38
352019-11-09 16:44:33.595 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x5ea84ce8
362019-11-09 16:44:33.596 T:1766748896 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/d/d50307b4.jpg 384x512
372019-11-09 16:44:33.596 T:1481614048 DEBUG: Thread BackgroundLoader 1481614048 terminating
382019-11-09 16:44:33.633 T:1915319856 DEBUG: ------ Window Deinit (DialogNotification.xml) ------
392019-11-09 16:44:33.860 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
402019-11-09 16:44:33.860 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
412019-11-09 16:44:33.861 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
422019-11-09 16:44:35.409 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
432019-11-09 16:44:35.410 T:1481614048 DEBUG: Thread Timer start, auto delete: false
442019-11-09 16:44:35.419 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
452019-11-09 16:44:35.419 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
462019-11-09 16:44:35.442 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x54e2dca8
472019-11-09 16:44:35.444 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(122256), nBufferAlignment(16)
482019-11-09 16:44:35.449 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x5ea84ce8
492019-11-09 16:44:35.451 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(89072), nBufferAlignment(16)
502019-11-09 16:44:35.451 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x5503c2d0
512019-11-09 16:44:35.453 T:1332511456 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
522019-11-09 16:44:35.469 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x5c979a38
532019-11-09 16:44:35.470 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x54e2dca8
542019-11-09 16:44:35.471 T:1766748896 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
552019-11-09 16:44:35.472 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x5503c2d0
562019-11-09 16:44:35.473 T:1332511456 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/d/d51fc4ec.jpg 683x512
572019-11-09 16:44:35.487 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x5ea84ce8
582019-11-09 16:44:35.489 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x5c979a38
592019-11-09 16:44:35.489 T:1766748896 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/9/9a7f7368.jpg 683x512
602019-11-09 16:44:35.529 T:1481614048 DEBUG: Thread Timer 1481614048 terminating
612019-11-09 16:44:35.559 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
622019-11-09 16:44:37.389 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
632019-11-09 16:44:37.390 T:1481614048 DEBUG: Thread Timer start, auto delete: false
642019-11-09 16:44:37.420 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
652019-11-09 16:44:37.531 T:1481614048 DEBUG: Thread Timer 1481614048 terminating
662019-11-09 16:44:37.559 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
672019-11-09 16:44:37.560 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
682019-11-09 16:44:37.564 T:1915319856 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fmetadata%2f96%2fchildren&mode=browseplex&plex_type=photo)
692019-11-09 16:44:37.564 T:1915319856 DEBUG: ParentPath = [plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fsections%2f3%2fall&mode=browseplex]
702019-11-09 16:44:37.566 T:1481614048 DEBUG: Thread waiting start, auto delete: false
712019-11-09 16:44:37.581 T:1332511456 DEBUG: CAddonDatabase::SetLastUsed[plugin.video.plexkodiconnect] took 17 ms
722019-11-09 16:44:37.582 T:1481614048 DEBUG: StartScript - calling plugin PlexKodiConnect('plugin://plugin.video.plexkodiconnect/','7','?id=3&key=%2flibrary%2fmetadata%2f96%2fchildren&mode=browseplex&plex_type=photo','resume:false')
732019-11-09 16:44:37.583 T:1599070944 DEBUG: Thread LanguageInvoker start, auto delete: false
742019-11-09 16:44:37.583 T:1599070944 INFO: initializing python engine.
752019-11-09 16:44:37.583 T:1599070944 DEBUG: CPythonInvoker(9, /home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py): start processing
762019-11-09 16:44:37.666 T:1915319856 DEBUG: ------ Window Init (DialogBusy.xml) ------
772019-11-09 16:44:37.858 T:1599070944 DEBUG: -->Python Interpreter Initialized<--
782019-11-09 16:44:37.858 T:1599070944 DEBUG: CPythonInvoker(9, /home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py): the source file to load is "/home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py"
792019-11-09 16:44:37.859 T:1599070944 DEBUG: CPythonInvoker(9, /home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py): setting the Python path to /home/osmc/.kodi/addons/plugin.video.plexkodiconnect:/home/osmc/.kodi/addons/script.module.certifi/lib:/home/osmc/.kodi/addons/script.module.chardet/lib:/home/osmc/.kodi/addons/script.module.defusedxml/lib:/home/osmc/.kodi/addons/script.module.idna/lib:/home/osmc/.kodi/addons/script.module.requests/lib:/home/osmc/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
802019-11-09 16:44:37.859 T:1599070944 DEBUG: CPythonInvoker(9, /home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py): entering source directory /home/osmc/.kodi/addons/plugin.video.plexkodiconnect
812019-11-09 16:44:37.859 T:1599070944 DEBUG: CPythonInvoker(9, /home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py): instantiating addon using automatically obtained id of "plugin.video.plexkodiconnect" dependent on version 2.1.0 of the xbmc.python api
822019-11-09 16:44:38.502 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
832019-11-09 16:44:38.503 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
842019-11-09 16:44:38.503 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
852019-11-09 16:44:38.553 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
862019-11-09 16:44:38.554 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
872019-11-09 16:44:38.559 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
882019-11-09 16:44:38.569 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
892019-11-09 16:44:38.569 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
902019-11-09 16:44:38.574 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
912019-11-09 16:44:38.696 T:1690739424 DEBUG: PLEX.kodimonitor: PKC settings change detected
922019-11-09 16:44:39.441 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
932019-11-09 16:44:39.441 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
942019-11-09 16:44:39.446 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
952019-11-09 16:44:39.475 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
962019-11-09 16:44:39.475 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
972019-11-09 16:44:39.480 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
982019-11-09 16:44:39.603 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
992019-11-09 16:44:39.603 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1002019-11-09 16:44:39.608 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1012019-11-09 16:44:39.615 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1022019-11-09 16:44:39.616 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1032019-11-09 16:44:39.620 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1042019-11-09 16:44:39.628 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1052019-11-09 16:44:39.629 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1062019-11-09 16:44:39.634 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1072019-11-09 16:44:39.643 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1082019-11-09 16:44:39.643 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1092019-11-09 16:44:39.648 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1102019-11-09 16:44:39.658 T:1599070944 NOTICE: PLEX.default: plugin.video.plexkodiconnect started
1112019-11-09 16:44:39.660 T:1599070944 DEBUG: PLEX.default: Full sys.argv received: ['plugin://plugin.video.plexkodiconnect/', '7', '?id=3&key=%2flibrary%2fmetadata%2f96%2fchildren&mode=browseplex&plex_type=photo', 'resume:false']
1122019-11-09 16:44:39.661 T:1599070944 DEBUG: PLEX.entrypoint: Browsing to key /library/metadata/96/children, section None, plex_type: photo, synched: True, prompt "None", args None
1132019-11-09 16:44:39.665 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1142019-11-09 16:44:39.665 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1152019-11-09 16:44:39.669 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1162019-11-09 16:44:39.677 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1172019-11-09 16:44:39.677 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1182019-11-09 16:44:39.682 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1192019-11-09 16:44:39.690 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1202019-11-09 16:44:39.690 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1212019-11-09 16:44:39.694 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1222019-11-09 16:44:39.702 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1232019-11-09 16:44:39.702 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1242019-11-09 16:44:39.707 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1252019-11-09 16:44:39.714 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1262019-11-09 16:44:39.714 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1272019-11-09 16:44:39.719 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1282019-11-09 16:44:39.727 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1292019-11-09 16:44:39.727 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1302019-11-09 16:44:39.732 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1312019-11-09 16:44:39.740 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1322019-11-09 16:44:39.740 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1332019-11-09 16:44:39.745 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1342019-11-09 16:44:39.753 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1352019-11-09 16:44:39.753 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1362019-11-09 16:44:39.758 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1372019-11-09 16:44:39.766 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1382019-11-09 16:44:39.766 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1392019-11-09 16:44:39.771 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1402019-11-09 16:44:39.778 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1412019-11-09 16:44:39.779 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1422019-11-09 16:44:39.783 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1432019-11-09 16:44:39.791 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1442019-11-09 16:44:39.791 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1452019-11-09 16:44:39.796 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1462019-11-09 16:44:39.803 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1472019-11-09 16:44:39.803 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1482019-11-09 16:44:39.808 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1492019-11-09 16:44:39.816 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1502019-11-09 16:44:39.816 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1512019-11-09 16:44:39.820 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1522019-11-09 16:44:39.828 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1532019-11-09 16:44:39.828 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1542019-11-09 16:44:39.833 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1552019-11-09 16:44:39.840 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1562019-11-09 16:44:39.840 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1572019-11-09 16:44:39.845 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1582019-11-09 16:44:39.853 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1592019-11-09 16:44:39.853 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1602019-11-09 16:44:39.858 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1612019-11-09 16:44:39.866 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1622019-11-09 16:44:39.866 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1632019-11-09 16:44:39.871 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1642019-11-09 16:44:39.879 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1652019-11-09 16:44:39.879 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1662019-11-09 16:44:39.884 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1672019-11-09 16:44:39.891 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1682019-11-09 16:44:39.891 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1692019-11-09 16:44:39.896 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1702019-11-09 16:44:39.904 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1712019-11-09 16:44:39.904 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1722019-11-09 16:44:39.908 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1732019-11-09 16:44:39.916 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1742019-11-09 16:44:39.916 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1752019-11-09 16:44:39.921 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1762019-11-09 16:44:39.928 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1772019-11-09 16:44:39.928 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1782019-11-09 16:44:39.933 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1792019-11-09 16:44:39.941 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1802019-11-09 16:44:39.941 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1812019-11-09 16:44:39.945 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1822019-11-09 16:44:39.954 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1832019-11-09 16:44:39.954 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1842019-11-09 16:44:39.959 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1852019-11-09 16:44:39.967 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1862019-11-09 16:44:39.967 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1872019-11-09 16:44:39.972 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1882019-11-09 16:44:39.979 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1892019-11-09 16:44:39.980 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1902019-11-09 16:44:39.984 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1912019-11-09 16:44:39.992 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1922019-11-09 16:44:39.992 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1932019-11-09 16:44:39.997 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1942019-11-09 16:44:40.004 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1952019-11-09 16:44:40.004 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1962019-11-09 16:44:40.009 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
1972019-11-09 16:44:40.017 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
1982019-11-09 16:44:40.017 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
1992019-11-09 16:44:40.021 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
2002019-11-09 16:44:40.029 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
2012019-11-09 16:44:40.029 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
2022019-11-09 16:44:40.034 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
2032019-11-09 16:44:40.041 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
2042019-11-09 16:44:40.041 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
2052019-11-09 16:44:40.046 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
2062019-11-09 16:44:40.053 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
2072019-11-09 16:44:40.053 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
2082019-11-09 16:44:40.058 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
2092019-11-09 16:44:40.066 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
2102019-11-09 16:44:40.067 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
2112019-11-09 16:44:40.071 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
2122019-11-09 16:44:40.076 T:1599070944 NOTICE: PLEX.download: Request session does not exist: start one
2132019-11-09 16:44:40.082 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting definitions
2142019-11-09 16:44:40.082 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: trying to load setting definitions from old format...
2152019-11-09 16:44:40.087 T:1599070944 DEBUG: CAddonSettings[plugin.video.plexkodiconnect]: loading setting values
2162019-11-09 16:44:40.093 T:1599070944 NOTICE: PLEX.clientinfo: Unique device Id plex_client_Id loaded: 1e1f43d4-961e-4043-a69b-6db0a9204695
2172019-11-09 16:44:40.095 T:1599070944 DEBUG: PLEX.download: Verify SSL certificates set to: None
2182019-11-09 16:44:40.095 T:1599070944 DEBUG: PLEX.download: SSL client side certificate set to: None
2192019-11-09 16:44:40.096 T:1599070944 DEBUG: PLEX.download: Requests session started on: https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400
2202019-11-09 16:44:40.217 T:1599070944 DEBUG: PLEX.entrypoint: show_listing: section_id None, synched True, key /library/metadata/96/children, plex_type photo, content type photos
2212019-11-09 16:44:40.436 T:1372566240 WARNING: Invalid media type "photo"
2222019-11-09 16:44:40.538 T:1599070944 WARNING: Previous line repeats 18 times.
2232019-11-09 16:44:40.538 T:1599070944 NOTICE: PLEX.default: plugin.video.plexkodiconnect stopped
2242019-11-09 16:44:40.539 T:1599070944 INFO: CPythonInvoker(9, /home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py): script successfully run
2252019-11-09 16:44:40.539 T:1599070944 DEBUG: onExecutionDone(9, /home/osmc/.kodi/addons/plugin.video.plexkodiconnect/default.py)
2262019-11-09 16:44:40.540 T:1481614048 DEBUG: Thread waiting 1481614048 terminating
2272019-11-09 16:44:40.540 T:1915319856 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
2282019-11-09 16:44:40.542 T:1915319856 DEBUG: Saving fileitems [plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fmetadata%2f96%2fchildren&mode=browseplex&plex_type=photo]
2292019-11-09 16:44:40.543 T:1915319856 DEBUG: -- items: 19, sort method: 0, ascending: false
2302019-11-09 16:44:40.546 T:1481614048 DEBUG: Thread BackgroundLoader start, auto delete: false
2312019-11-09 16:44:40.547 T:1481614048 DEBUG: Thread BackgroundLoader 1481614048 terminating
2322019-11-09 16:44:40.555 T:1481614048 DEBUG: Thread BackgroundLoader start, auto delete: false
2332019-11-09 16:44:40.564 T:1481614048 DEBUG: Thread BackgroundLoader 1481614048 terminating
2342019-11-09 16:44:40.717 T:1599070944 INFO: Python interpreter stopped
2352019-11-09 16:44:40.717 T:1599070944 DEBUG: Thread LanguageInvoker 1599070944 terminating
2362019-11-09 16:44:41.161 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
2372019-11-09 16:44:41.161 T:1599070944 DEBUG: Thread Timer start, auto delete: false
2382019-11-09 16:44:41.172 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
2392019-11-09 16:44:41.172 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
2402019-11-09 16:44:41.174 T:1332511456 INFO: easy_acquire - Created session to https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct
2412019-11-09 16:44:41.301 T:1599070944 INFO: Previous line repeats 1 times.
2422019-11-09 16:44:41.301 T:1599070944 DEBUG: Thread Timer 1599070944 terminating
2432019-11-09 16:44:41.309 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
2442019-11-09 16:44:41.346 T:1332511456 DEBUG: CurlFile::Open(0x6d59b150) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97
2452019-11-09 16:44:41.375 T:1766748896 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/97/thumb/1573154578&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
2462019-11-09 16:44:41.376 T:1766748896 DEBUG: CFileCache::Open - opening <photo/:/transcode> using cache
2472019-11-09 16:44:41.376 T:1766748896 DEBUG: CurlFile::Open(0x5c9f7400) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/97/thumb/1573154578&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
2482019-11-09 16:44:41.527 T:1380958944 DEBUG: Thread FileCache start, auto delete: false
2492019-11-09 16:44:41.534 T:1380958944 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
2502019-11-09 16:44:41.584 T:1766748896 INFO: easy_acquire - Created session to https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct
2512019-11-09 16:44:41.604 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x6d587610
2522019-11-09 16:44:41.605 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(1682224), nBufferAlignment(16)
2532019-11-09 16:44:41.619 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x6d53adf8
2542019-11-09 16:44:41.621 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x6d53ae58
2552019-11-09 16:44:41.621 T:1332511456 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(391680) nBufferAlignment(16)
2562019-11-09 16:44:41.774 T:1766748896 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/97/thumb/1573154578&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
2572019-11-09 16:44:41.828 T:1380958944 DEBUG: Thread FileCache 1380958944 terminating
2582019-11-09 16:44:41.828 T:1766748896 DEBUG: Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/97/thumb/1573154578&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx' to '7/7b67bf47.jpg':
2592019-11-09 16:44:41.828 T:1766748896 DEBUG: cached image 'special://masterprofile/Thumbnails/7/7b67bf47.jpg' size 683x512
2602019-11-09 16:44:41.833 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x5eafd8f8
2612019-11-09 16:44:41.837 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_encode) - port(340), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(1409024), nBufferAlignment(16)
2622019-11-09 16:44:41.838 T:1766748896 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(349696) nBufferAlignment(16)
2632019-11-09 16:44:41.901 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
2642019-11-09 16:44:41.901 T:1380958944 DEBUG: Thread Timer start, auto delete: false
2652019-11-09 16:44:41.916 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
2662019-11-09 16:44:41.939 T:1766748896 DEBUG: COMXImageEnc::CreateThumbnailFromSurface : special://masterprofile/Thumbnails/7/7b67bf47.jpg width 683 height 512
2672019-11-09 16:44:41.941 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x5eafd8f8
2682019-11-09 16:44:41.943 T:1332511456 DEBUG: COMXReEnc::ReEncode : https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97 2560x1920 -> 720x540
2692019-11-09 16:44:41.946 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x6d587610
2702019-11-09 16:44:41.952 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x6d53adf8
2712019-11-09 16:44:41.953 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x6d53ae58
2722019-11-09 16:44:41.977 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x6d587610
2732019-11-09 16:44:41.978 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(153408), nBufferAlignment(16)
2742019-11-09 16:44:41.981 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x55091920
2752019-11-09 16:44:41.982 T:1332511456 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
2762019-11-09 16:44:42.008 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x6d587610
2772019-11-09 16:44:42.009 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x55091920
2782019-11-09 16:44:42.010 T:1332511456 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/9/96893d53.jpg 720x540
2792019-11-09 16:44:42.010 T:1332511456 DEBUG: Fast Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97' to '9/96893d53.jpg': 0x5c7c894
2802019-11-09 16:44:42.041 T:1380958944 DEBUG: Thread Timer 1380958944 terminating
2812019-11-09 16:44:42.043 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
2822019-11-09 16:44:42.043 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
2832019-11-09 16:44:42.049 T:1915319856 DEBUG: OnPlayMedia https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97
2842019-11-09 16:44:42.050 T:1380958944 DEBUG: Thread GUIDialogCache start, auto delete: true
2852019-11-09 16:44:42.055 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97 -> image/jpeg
2862019-11-09 16:44:42.060 T:1380958944 DEBUG: Thread GUIDialogCache 1380958944 terminating (autodelete)
2872019-11-09 16:44:42.079 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97)
2882019-11-09 16:44:42.079 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2892019-11-09 16:44:42.079 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2902019-11-09 16:44:42.079 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2912019-11-09 16:44:42.079 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2922019-11-09 16:44:42.079 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2932019-11-09 16:44:42.080 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2942019-11-09 16:44:42.080 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
2952019-11-09 16:44:42.080 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
2962019-11-09 16:44:42.080 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
2972019-11-09 16:44:42.080 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
2982019-11-09 16:44:42.080 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2992019-11-09 16:44:42.080 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
3002019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
3012019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
3022019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
3032019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
3042019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
3052019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
3062019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
3072019-11-09 16:44:42.081 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
3082019-11-09 16:44:42.085 T:1915319856 DEBUG: CMMALPool::CMMALPool Created pool 0x5957270 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
3092019-11-09 16:44:42.094 T:1915319856 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
3102019-11-09 16:44:42.094 T:1915319856 NOTICE: VideoPlayer::OpenFile: https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97
3112019-11-09 16:44:42.099 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97 -> image/jpeg
3122019-11-09 16:44:42.100 T:1915319856 DEBUG: CMMALRenderer::CMMALRenderer
3132019-11-09 16:44:42.100 T:1599070944 DEBUG: Thread MMALProcess start, auto delete: false
3142019-11-09 16:44:42.100 T:1915319856 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
3152019-11-09 16:44:42.100 T:1397744352 DEBUG: Thread VideoPlayer start, auto delete: false
3162019-11-09 16:44:42.100 T:1599070944 DEBUG: CMMALRenderer::Run - starting
3172019-11-09 16:44:42.101 T:1397744352 NOTICE: Creating InputStream
3182019-11-09 16:44:42.102 T:1397744352 DEBUG: CurlFile::Open(0x534fce90) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97
3192019-11-09 16:44:42.110 T:1766748896 DEBUG: Loading settings for https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97
3202019-11-09 16:44:42.116 T:1915319856 DEBUG: CVideoGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97)
3212019-11-09 16:44:42.116 T:1915319856 DEBUG: CPlayerGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97)
3222019-11-09 16:44:42.116 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
3232019-11-09 16:44:42.116 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
3242019-11-09 16:44:42.118 T:1397744352 DEBUG: CFileCache::Open - opening <library/parts/93/1573154055/file.jpg> using cache
3252019-11-09 16:44:42.118 T:1397744352 DEBUG: CurlFile::Open(0x6f1ceb50) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97
3262019-11-09 16:44:42.121 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Info.OnChanged Data: None
3272019-11-09 16:44:42.127 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
3282019-11-09 16:44:42.127 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
3292019-11-09 16:44:42.168 T:1372566240 DEBUG: Thread FileCache start, auto delete: false
3302019-11-09 16:44:42.168 T:1397744352 NOTICE: Creating Demuxer
3312019-11-09 16:44:42.216 T:1915319856 DEBUG: ------ Window Init (DialogBusy.xml) ------
3322019-11-09 16:44:42.222 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnPlay Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'030'}, u'player': {u'playerid': -1, u'speed': 1}}
3332019-11-09 16:44:42.235 T:1372566240 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
3342019-11-09 16:44:42.434 T:1690739424 DEBUG: PLEX.kodimonitor: Detected position 0 for {'playlistid': 1, 'id': None, 'version': None, 'type': 'video', 'selectedItemID': None, 'selectedItemOffset': None, 'shuffled': 0, 'repeat': 0, 'kodi_playlist_playback': False, 'pkc_edit': False, 'items': []}
3352019-11-09 16:44:42.434 T:1690739424 DEBUG: PLEX.kodimonitor: Position 0 not in PKC playqueue yet
3362019-11-09 16:44:42.434 T:1690739424 DEBUG: PLEX.kodimonitor: Need to initialize Plex and PKC playqueue
3372019-11-09 16:44:42.578 T:1397744352 DEBUG: ffmpeg[534FE2E0]: Format jpeg_pipe detected only with low score of 25, misdetection possible!
3382019-11-09 16:44:42.578 T:1397744352 DEBUG: Open - probing detected format [jpeg_pipe]
3392019-11-09 16:44:42.578 T:1397744352 DEBUG: Open - avformat_find_stream_info starting
3402019-11-09 16:44:42.642 T:1397744352 DEBUG: Open - av_find_stream_info finished
3412019-11-09 16:44:42.642 T:1397744352 INFO: ffmpeg[534FE2E0]: Input #0, jpeg_pipe, from 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/93/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=97':
3422019-11-09 16:44:42.642 T:1397744352 INFO: ffmpeg[534FE2E0]: Duration: N/A, bitrate: N/A
3432019-11-09 16:44:42.643 T:1397744352 INFO: ffmpeg[534FE2E0]: Stream #0:0: Video: mjpeg, yuvj420p(pc, bt470bg/unknown/unknown), 2560x1920, 25 tbr, 25 tbn, 25 tbc
3442019-11-09 16:44:42.643 T:1397744352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
3452019-11-09 16:44:42.643 T:1397744352 NOTICE: Opening stream: 0 source: 256
3462019-11-09 16:44:42.643 T:1397744352 NOTICE: Creating video codec with codec id: 7
3472019-11-09 16:44:42.646 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x55609240 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV)
3482019-11-09 16:44:42.650 T:1397744352 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 7
3492019-11-09 16:44:42.652 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x5db278c0 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
3502019-11-09 16:44:42.652 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x5957270 for port vc.ril.video_decode:out:0(I420)
3512019-11-09 16:44:42.653 T:1397744352 NOTICE: Creating video thread
3522019-11-09 16:44:42.653 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
3532019-11-09 16:44:42.653 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
3542019-11-09 16:44:42.655 T:1380958944 DEBUG: Thread VideoPlayerVideo start, auto delete: false
3552019-11-09 16:44:42.655 T:1380958944 NOTICE: running thread: video_thread
3562019-11-09 16:44:42.655 T:1380958944 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
3572019-11-09 16:44:42.657 T:1380958944 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
3582019-11-09 16:44:42.657 T:1397744352 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0
3592019-11-09 16:44:42.657 T:1397744352 NOTICE: CVideoPlayer::OnExit()
3602019-11-09 16:44:42.657 T:1397744352 NOTICE: VideoPlayer: eof, waiting for queues to empty
3612019-11-09 16:44:42.658 T:1397744352 DEBUG: CVideoPlayer: updating file item stream details with current streams
3622019-11-09 16:44:42.658 T:1397744352 NOTICE: Closing stream player 2
3632019-11-09 16:44:42.658 T:1397744352 NOTICE: waiting for video thread to exit
3642019-11-09 16:44:42.662 T:1380958944 NOTICE: thread end: video_thread
3652019-11-09 16:44:42.663 T:1380958944 DEBUG: Thread VideoPlayerVideo 1380958944 terminating
3662019-11-09 16:44:42.663 T:1397744352 NOTICE: deleting video codec
3672019-11-09 16:44:42.725 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x55609240 for port vc.ril.video_decode:out:0(OPQV)
3682019-11-09 16:44:42.727 T:1372566240 DEBUG: Thread FileCache 1372566240 terminating
3692019-11-09 16:44:42.728 T:1397744352 DEBUG: Thread VideoPlayer 1397744352 terminating
3702019-11-09 16:44:42.736 T:1766748896 DEBUG: OnPlayBackEnded: CApplication::OnPlayBackEnded
3712019-11-09 16:44:42.745 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
3722019-11-09 16:44:42.745 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
3732019-11-09 16:44:42.786 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
3742019-11-09 16:44:42.786 T:1915319856 DEBUG: DeleteRenderer - deleting renderer
3752019-11-09 16:44:42.787 T:1915319856 DEBUG: CMMALRenderer::~CMMALRenderer
3762019-11-09 16:44:42.787 T:1915319856 DEBUG: CMMALRenderer::UnInitMMAL
3772019-11-09 16:44:42.787 T:1599070944 DEBUG: CMMALRenderer::Run - stopping
3782019-11-09 16:44:42.787 T:1599070944 DEBUG: Thread MMALProcess 1599070944 terminating
3792019-11-09 16:44:42.787 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
3802019-11-09 16:44:42.787 T:1915319856 NOTICE: VideoPlayer: finished waiting
3812019-11-09 16:44:42.787 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
3822019-11-09 16:44:42.787 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
3832019-11-09 16:44:42.787 T:1915319856 NOTICE: VideoPlayer: finished waiting
3842019-11-09 16:44:42.789 T:1915319856 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
3852019-11-09 16:44:42.789 T:1915319856 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x5db278c0 for port vc.ril.video_decode:out:0(I420)
3862019-11-09 16:44:42.802 T:1915319856 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
3872019-11-09 16:44:42.835 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnStop Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'030'}, u'end': True}
3882019-11-09 16:44:42.836 T:1690739424 DEBUG: PLEX.kodimonitor: playback_cleanup called. Active players: set([])
3892019-11-09 16:44:42.837 T:1690739424 DEBUG: PLEX.kodimonitor: Finished PKC playback cleanup
3902019-11-09 16:44:43.436 T:1690739424 DEBUG: PLEX.kodimonitor: Kodi playing item properties: {u'title': u'', u'type': u'unknown', u'file': u'', u'label': u''}
3912019-11-09 16:44:43.436 T:1690739424 DEBUG: PLEX.kodimonitor: No Plex id obtained - aborting playback report
3922019-11-09 16:44:43.700 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
3932019-11-09 16:44:43.701 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
3942019-11-09 16:44:43.701 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
3952019-11-09 16:44:44.075 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
3962019-11-09 16:44:44.076 T:1389351648 DEBUG: Thread Timer start, auto delete: false
3972019-11-09 16:44:44.081 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
3982019-11-09 16:44:44.081 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
3992019-11-09 16:44:44.102 T:1332511456 DEBUG: CurlFile::Open(0x6d53aa60) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98
4002019-11-09 16:44:44.145 T:1766748896 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/98/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
4012019-11-09 16:44:44.159 T:1766748896 DEBUG: CFileCache::Open - opening <photo/:/transcode> using cache
4022019-11-09 16:44:44.160 T:1766748896 DEBUG: CurlFile::Open(0x5c9f7400) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/98/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
4032019-11-09 16:44:44.170 T:1599070944 DEBUG: Thread FileCache start, auto delete: false
4042019-11-09 16:44:44.176 T:1389351648 DEBUG: Thread Timer 1389351648 terminating
4052019-11-09 16:44:44.177 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
4062019-11-09 16:44:44.191 T:1599070944 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
4072019-11-09 16:44:44.226 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x6d5d3500
4082019-11-09 16:44:44.227 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(1728672), nBufferAlignment(16)
4092019-11-09 16:44:44.241 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x6d5860a8
4102019-11-09 16:44:44.244 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x6d5869d8
4112019-11-09 16:44:44.244 T:1766748896 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/98/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
4122019-11-09 16:44:44.244 T:1332511456 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(391680) nBufferAlignment(16)
4132019-11-09 16:44:44.300 T:1599070944 DEBUG: Thread FileCache 1599070944 terminating
4142019-11-09 16:44:44.300 T:1766748896 DEBUG: Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/98/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx' to 'e/e254fef5.jpg':
4152019-11-09 16:44:44.300 T:1766748896 DEBUG: cached image 'special://masterprofile/Thumbnails/e/e254fef5.jpg' size 683x512
4162019-11-09 16:44:44.305 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x5583bc20
4172019-11-09 16:44:44.311 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_encode) - port(340), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(1409024), nBufferAlignment(16)
4182019-11-09 16:44:44.314 T:1766748896 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(349696) nBufferAlignment(16)
4192019-11-09 16:44:44.565 T:1766748896 DEBUG: COMXImageEnc::CreateThumbnailFromSurface : special://masterprofile/Thumbnails/e/e254fef5.jpg width 683 height 512
4202019-11-09 16:44:44.567 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x5583bc20
4212019-11-09 16:44:44.569 T:1332511456 DEBUG: COMXReEnc::ReEncode : https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98 2560x1920 -> 720x540
4222019-11-09 16:44:44.571 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x6d5d3500
4232019-11-09 16:44:44.572 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x6d5860a8
4242019-11-09 16:44:44.572 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x6d5869d8
4252019-11-09 16:44:44.582 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x6d587610
4262019-11-09 16:44:44.582 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(164032), nBufferAlignment(16)
4272019-11-09 16:44:44.585 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x6d5db448
4282019-11-09 16:44:44.586 T:1332511456 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
4292019-11-09 16:44:44.608 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x6d587610
4302019-11-09 16:44:44.609 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x6d5db448
4312019-11-09 16:44:44.609 T:1332511456 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/c/ceabf18e.jpg 720x540
4322019-11-09 16:44:44.610 T:1332511456 DEBUG: Fast Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98' to 'c/ceabf18e.jpg': 0x5e1ad14
4332019-11-09 16:44:44.939 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
4342019-11-09 16:44:44.940 T:1599070944 DEBUG: Thread Timer start, auto delete: false
4352019-11-09 16:44:44.943 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
4362019-11-09 16:44:45.059 T:1599070944 DEBUG: Thread Timer 1599070944 terminating
4372019-11-09 16:44:45.076 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
4382019-11-09 16:44:45.076 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
4392019-11-09 16:44:45.083 T:1915319856 DEBUG: OnPlayMedia https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98
4402019-11-09 16:44:45.084 T:1599070944 DEBUG: Thread GUIDialogCache start, auto delete: true
4412019-11-09 16:44:45.090 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98 -> image/jpeg
4422019-11-09 16:44:45.094 T:1599070944 DEBUG: Thread GUIDialogCache 1599070944 terminating (autodelete)
4432019-11-09 16:44:45.113 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98)
4442019-11-09 16:44:45.113 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
4452019-11-09 16:44:45.113 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
4462019-11-09 16:44:45.113 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
4472019-11-09 16:44:45.113 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
4482019-11-09 16:44:45.113 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
4492019-11-09 16:44:45.114 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
4502019-11-09 16:44:45.114 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
4512019-11-09 16:44:45.114 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
4522019-11-09 16:44:45.114 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
4532019-11-09 16:44:45.114 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
4542019-11-09 16:44:45.114 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
4552019-11-09 16:44:45.114 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
4562019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
4572019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
4582019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
4592019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
4602019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
4612019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
4622019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
4632019-11-09 16:44:45.115 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
4642019-11-09 16:44:45.120 T:1915319856 DEBUG: CMMALPool::CMMALPool Created pool 0x5c5aa60 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
4652019-11-09 16:44:45.128 T:1915319856 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
4662019-11-09 16:44:45.129 T:1915319856 NOTICE: VideoPlayer::OpenFile: https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98
4672019-11-09 16:44:45.134 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98 -> image/jpeg
4682019-11-09 16:44:45.134 T:1915319856 DEBUG: CMMALRenderer::CMMALRenderer
4692019-11-09 16:44:45.135 T:1389351648 DEBUG: Thread MMALProcess start, auto delete: false
4702019-11-09 16:44:45.135 T:1915319856 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
4712019-11-09 16:44:45.135 T:1397744352 DEBUG: Thread VideoPlayer start, auto delete: false
4722019-11-09 16:44:45.135 T:1389351648 DEBUG: CMMALRenderer::Run - starting
4732019-11-09 16:44:45.135 T:1397744352 NOTICE: Creating InputStream
4742019-11-09 16:44:45.137 T:1397744352 DEBUG: CurlFile::Open(0x534fce90) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98
4752019-11-09 16:44:45.156 T:1915319856 DEBUG: CVideoGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98)
4762019-11-09 16:44:45.157 T:1915319856 DEBUG: CPlayerGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98)
4772019-11-09 16:44:45.157 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
4782019-11-09 16:44:45.157 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
4792019-11-09 16:44:45.158 T:1397744352 DEBUG: CFileCache::Open - opening <library/parts/94/1573154055/file.jpg> using cache
4802019-11-09 16:44:45.160 T:1397744352 DEBUG: CurlFile::Open(0x719173e0) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98
4812019-11-09 16:44:45.186 T:1766748896 DEBUG: Loading settings for https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98
4822019-11-09 16:44:45.194 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
4832019-11-09 16:44:45.194 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
4842019-11-09 16:44:45.248 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Info.OnChanged Data: None
4852019-11-09 16:44:45.249 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnPlay Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'031'}, u'player': {u'playerid': -1, u'speed': 1}}
4862019-11-09 16:44:45.257 T:1915319856 DEBUG: ------ Window Init (DialogBusy.xml) ------
4872019-11-09 16:44:45.270 T:1444922080 DEBUG: Thread FileCache start, auto delete: false
4882019-11-09 16:44:45.270 T:1397744352 NOTICE: Creating Demuxer
4892019-11-09 16:44:45.341 T:1444922080 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
4902019-11-09 16:44:45.473 T:1690739424 DEBUG: PLEX.kodimonitor: Detected position 0 for {'playlistid': 1, 'id': None, 'version': None, 'type': 'video', 'selectedItemID': None, 'selectedItemOffset': None, 'shuffled': 0, 'repeat': 0, 'kodi_playlist_playback': False, 'pkc_edit': False, 'items': []}
4912019-11-09 16:44:45.474 T:1690739424 DEBUG: PLEX.kodimonitor: Position 0 not in PKC playqueue yet
4922019-11-09 16:44:45.474 T:1690739424 DEBUG: PLEX.kodimonitor: Need to initialize Plex and PKC playqueue
4932019-11-09 16:44:45.685 T:1397744352 DEBUG: ffmpeg[534FE2E0]: Format jpeg_pipe detected only with low score of 25, misdetection possible!
4942019-11-09 16:44:45.686 T:1397744352 DEBUG: Open - probing detected format [jpeg_pipe]
4952019-11-09 16:44:45.686 T:1397744352 DEBUG: Open - avformat_find_stream_info starting
4962019-11-09 16:44:45.751 T:1397744352 DEBUG: Open - av_find_stream_info finished
4972019-11-09 16:44:45.751 T:1397744352 INFO: ffmpeg[534FE2E0]: Input #0, jpeg_pipe, from 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/94/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=98':
4982019-11-09 16:44:45.751 T:1397744352 INFO: ffmpeg[534FE2E0]: Duration: N/A, bitrate: N/A
4992019-11-09 16:44:45.751 T:1397744352 INFO: ffmpeg[534FE2E0]: Stream #0:0: Video: mjpeg, yuvj420p(pc, bt470bg/unknown/unknown), 2560x1920, 25 tbr, 25 tbn, 25 tbc
5002019-11-09 16:44:45.751 T:1397744352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
5012019-11-09 16:44:45.751 T:1397744352 NOTICE: Opening stream: 0 source: 256
5022019-11-09 16:44:45.752 T:1397744352 NOTICE: Creating video codec with codec id: 7
5032019-11-09 16:44:45.755 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x71916ac0 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV)
5042019-11-09 16:44:45.760 T:1397744352 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 7
5052019-11-09 16:44:45.762 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x55229990 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
5062019-11-09 16:44:45.762 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x5c5aa60 for port vc.ril.video_decode:out:0(I420)
5072019-11-09 16:44:45.763 T:1397744352 NOTICE: Creating video thread
5082019-11-09 16:44:45.763 T:1599070944 DEBUG: Thread VideoPlayerVideo start, auto delete: false
5092019-11-09 16:44:45.763 T:1599070944 NOTICE: running thread: video_thread
5102019-11-09 16:44:45.763 T:1599070944 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
5112019-11-09 16:44:45.763 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
5122019-11-09 16:44:45.763 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
5132019-11-09 16:44:45.763 T:1397744352 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0
5142019-11-09 16:44:45.767 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
5152019-11-09 16:44:45.767 T:1599070944 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
5162019-11-09 16:44:45.767 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
5172019-11-09 16:44:45.768 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 0
5182019-11-09 16:44:45.768 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
5192019-11-09 16:44:45.768 T:1397744352 NOTICE: CVideoPlayer::OnExit()
5202019-11-09 16:44:45.768 T:1397744352 NOTICE: VideoPlayer: eof, waiting for queues to empty
5212019-11-09 16:44:45.768 T:1397744352 DEBUG: CVideoPlayer: updating file item stream details with current streams
5222019-11-09 16:44:45.768 T:1397744352 NOTICE: Closing stream player 2
5232019-11-09 16:44:45.768 T:1397744352 NOTICE: waiting for video thread to exit
5242019-11-09 16:44:45.773 T:1599070944 NOTICE: thread end: video_thread
5252019-11-09 16:44:45.773 T:1599070944 DEBUG: Thread VideoPlayerVideo 1599070944 terminating
5262019-11-09 16:44:45.773 T:1397744352 NOTICE: deleting video codec
5272019-11-09 16:44:45.835 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x71916ac0 for port vc.ril.video_decode:out:0(OPQV)
5282019-11-09 16:44:45.837 T:1444922080 DEBUG: Thread FileCache 1444922080 terminating
5292019-11-09 16:44:45.838 T:1397744352 DEBUG: Thread VideoPlayer 1397744352 terminating
5302019-11-09 16:44:45.846 T:1766748896 DEBUG: OnPlayBackEnded: CApplication::OnPlayBackEnded
5312019-11-09 16:44:45.855 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
5322019-11-09 16:44:45.855 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
5332019-11-09 16:44:45.875 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnStop Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'031'}, u'end': True}
5342019-11-09 16:44:45.875 T:1690739424 DEBUG: PLEX.kodimonitor: playback_cleanup called. Active players: set([])
5352019-11-09 16:44:45.876 T:1690739424 DEBUG: PLEX.kodimonitor: Finished PKC playback cleanup
5362019-11-09 16:44:45.908 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
5372019-11-09 16:44:45.908 T:1915319856 DEBUG: DeleteRenderer - deleting renderer
5382019-11-09 16:44:45.908 T:1915319856 DEBUG: CMMALRenderer::~CMMALRenderer
5392019-11-09 16:44:45.908 T:1915319856 DEBUG: CMMALRenderer::UnInitMMAL
5402019-11-09 16:44:45.908 T:1389351648 DEBUG: CMMALRenderer::Run - stopping
5412019-11-09 16:44:45.909 T:1389351648 DEBUG: Thread MMALProcess 1389351648 terminating
5422019-11-09 16:44:45.909 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
5432019-11-09 16:44:45.909 T:1915319856 NOTICE: VideoPlayer: finished waiting
5442019-11-09 16:44:45.909 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
5452019-11-09 16:44:45.909 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
5462019-11-09 16:44:45.909 T:1915319856 NOTICE: VideoPlayer: finished waiting
5472019-11-09 16:44:45.911 T:1915319856 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
5482019-11-09 16:44:45.911 T:1915319856 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x55229990 for port vc.ril.video_decode:out:0(I420)
5492019-11-09 16:44:45.923 T:1915319856 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
5502019-11-09 16:44:46.411 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
5512019-11-09 16:44:46.411 T:1351590624 DEBUG: Thread Timer start, auto delete: false
5522019-11-09 16:44:46.452 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
5532019-11-09 16:44:46.452 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
5542019-11-09 16:44:46.469 T:1332511456 DEBUG: CurlFile::Open(0x6d53aa60) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99
5552019-11-09 16:44:46.476 T:1690739424 DEBUG: PLEX.kodimonitor: Kodi playing item properties: {u'title': u'', u'type': u'unknown', u'file': u'', u'label': u''}
5562019-11-09 16:44:46.476 T:1690739424 DEBUG: PLEX.kodimonitor: No Plex id obtained - aborting playback report
5572019-11-09 16:44:46.503 T:1766748896 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/99/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
5582019-11-09 16:44:46.504 T:1766748896 DEBUG: CFileCache::Open - opening <photo/:/transcode> using cache
5592019-11-09 16:44:46.504 T:1766748896 DEBUG: CurlFile::Open(0x5c9f7400) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/99/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
5602019-11-09 16:44:46.510 T:1389351648 DEBUG: Thread FileCache start, auto delete: false
5612019-11-09 16:44:46.512 T:1351590624 DEBUG: Thread Timer 1351590624 terminating
5622019-11-09 16:44:46.518 T:1389351648 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
5632019-11-09 16:44:46.526 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
5642019-11-09 16:44:46.570 T:1766748896 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/99/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
5652019-11-09 16:44:46.575 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x6d5d3b18
5662019-11-09 16:44:46.576 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(1656976), nBufferAlignment(16)
5672019-11-09 16:44:46.591 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x6d5d3a48
5682019-11-09 16:44:46.593 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x6d5860a8
5692019-11-09 16:44:46.594 T:1332511456 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(391680) nBufferAlignment(16)
5702019-11-09 16:44:46.629 T:1389351648 DEBUG: Thread FileCache 1389351648 terminating
5712019-11-09 16:44:46.629 T:1766748896 DEBUG: Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/99/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx' to '7/750b1be4.jpg':
5722019-11-09 16:44:46.629 T:1766748896 DEBUG: cached image 'special://masterprofile/Thumbnails/7/750b1be4.jpg' size 683x512
5732019-11-09 16:44:46.636 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x5eafd8f8
5742019-11-09 16:44:46.643 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_encode) - port(340), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(1409024), nBufferAlignment(16)
5752019-11-09 16:44:46.646 T:1766748896 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(349696) nBufferAlignment(16)
5762019-11-09 16:44:46.966 T:1766748896 DEBUG: COMXImageEnc::CreateThumbnailFromSurface : special://masterprofile/Thumbnails/7/750b1be4.jpg width 683 height 512
5772019-11-09 16:44:46.973 T:1332511456 DEBUG: COMXReEnc::ReEncode : https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99 2560x1920 -> 720x540
5782019-11-09 16:44:46.975 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x5eafd8f8
5792019-11-09 16:44:46.975 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x6d5d3b18
5802019-11-09 16:44:46.976 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x6d5d3a48
5812019-11-09 16:44:46.977 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x6d5860a8
5822019-11-09 16:44:46.987 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x6d587610
5832019-11-09 16:44:46.987 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(138512), nBufferAlignment(16)
5842019-11-09 16:44:46.990 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x6d53aeb8
5852019-11-09 16:44:46.991 T:1332511456 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
5862019-11-09 16:44:47.012 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x6d587610
5872019-11-09 16:44:47.013 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x6d53aeb8
5882019-11-09 16:44:47.013 T:1332511456 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/0/04fe423b.jpg 720x540
5892019-11-09 16:44:47.013 T:1332511456 DEBUG: Fast Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99' to '0/04fe423b.jpg': 0x5e1ad14
5902019-11-09 16:44:47.021 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
5912019-11-09 16:44:47.021 T:1389351648 DEBUG: Thread Timer start, auto delete: false
5922019-11-09 16:44:47.027 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
5932019-11-09 16:44:47.123 T:1389351648 DEBUG: Thread Timer 1389351648 terminating
5942019-11-09 16:44:47.142 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
5952019-11-09 16:44:47.143 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
5962019-11-09 16:44:47.146 T:1915319856 DEBUG: OnPlayMedia https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99
5972019-11-09 16:44:47.147 T:1389351648 DEBUG: Thread GUIDialogCache start, auto delete: true
5982019-11-09 16:44:47.151 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99 -> image/jpeg
5992019-11-09 16:44:47.157 T:1389351648 DEBUG: Thread GUIDialogCache 1389351648 terminating (autodelete)
6002019-11-09 16:44:47.162 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99)
6012019-11-09 16:44:47.162 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
6022019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
6032019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
6042019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
6052019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
6062019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
6072019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
6082019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
6092019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
6102019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
6112019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
6122019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
6132019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
6142019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
6152019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
6162019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
6172019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
6182019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
6192019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
6202019-11-09 16:44:47.163 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
6212019-11-09 16:44:47.166 T:1915319856 DEBUG: CMMALPool::CMMALPool Created pool 0x604d770 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
6222019-11-09 16:44:47.171 T:1915319856 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
6232019-11-09 16:44:47.171 T:1915319856 NOTICE: VideoPlayer::OpenFile: https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99
6242019-11-09 16:44:47.176 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99 -> image/jpeg
6252019-11-09 16:44:47.176 T:1915319856 DEBUG: CMMALRenderer::CMMALRenderer
6262019-11-09 16:44:47.176 T:1351590624 DEBUG: Thread MMALProcess start, auto delete: false
6272019-11-09 16:44:47.177 T:1915319856 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
6282019-11-09 16:44:47.177 T:1397744352 DEBUG: Thread VideoPlayer start, auto delete: false
6292019-11-09 16:44:47.177 T:1351590624 DEBUG: CMMALRenderer::Run - starting
6302019-11-09 16:44:47.177 T:1397744352 NOTICE: Creating InputStream
6312019-11-09 16:44:47.178 T:1397744352 DEBUG: CurlFile::Open(0x534fce90) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99
6322019-11-09 16:44:47.186 T:1766748896 DEBUG: Loading settings for https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99
6332019-11-09 16:44:47.190 T:1915319856 DEBUG: CVideoGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99)
6342019-11-09 16:44:47.191 T:1915319856 DEBUG: CPlayerGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99)
6352019-11-09 16:44:47.191 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
6362019-11-09 16:44:47.191 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
6372019-11-09 16:44:47.204 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
6382019-11-09 16:44:47.204 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
6392019-11-09 16:44:47.209 T:1397744352 DEBUG: CFileCache::Open - opening <library/parts/95/1573154055/file.jpg> using cache
6402019-11-09 16:44:47.209 T:1397744352 DEBUG: CurlFile::Open(0x6e71b7a8) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99
6412019-11-09 16:44:47.259 T:1444922080 DEBUG: Thread FileCache start, auto delete: false
6422019-11-09 16:44:47.260 T:1397744352 NOTICE: Creating Demuxer
6432019-11-09 16:44:47.285 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Info.OnChanged Data: None
6442019-11-09 16:44:47.286 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnPlay Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'032'}, u'player': {u'playerid': -1, u'speed': 1}}
6452019-11-09 16:44:47.293 T:1915319856 DEBUG: ------ Window Init (DialogBusy.xml) ------
6462019-11-09 16:44:47.330 T:1444922080 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
6472019-11-09 16:44:47.382 T:1552937696 DEBUG: PLEX.subscribers: PKC playqueue is still initializing - skip update
6482019-11-09 16:44:47.510 T:1690739424 DEBUG: PLEX.kodimonitor: Detected position 0 for {'playlistid': 1, 'id': None, 'version': None, 'type': 'video', 'selectedItemID': None, 'selectedItemOffset': None, 'shuffled': 0, 'repeat': 0, 'kodi_playlist_playback': False, 'pkc_edit': False, 'items': []}
6492019-11-09 16:44:47.511 T:1690739424 DEBUG: PLEX.kodimonitor: Position 0 not in PKC playqueue yet
6502019-11-09 16:44:47.511 T:1690739424 DEBUG: PLEX.kodimonitor: Need to initialize Plex and PKC playqueue
6512019-11-09 16:44:47.777 T:1397744352 DEBUG: ffmpeg[534FE2E0]: Format jpeg_pipe detected only with low score of 25, misdetection possible!
6522019-11-09 16:44:47.777 T:1397744352 DEBUG: Open - probing detected format [jpeg_pipe]
6532019-11-09 16:44:47.777 T:1397744352 DEBUG: Open - avformat_find_stream_info starting
6542019-11-09 16:44:47.837 T:1397744352 DEBUG: Open - av_find_stream_info finished
6552019-11-09 16:44:47.838 T:1397744352 INFO: ffmpeg[534FE2E0]: Input #0, jpeg_pipe, from 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/95/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=99':
6562019-11-09 16:44:47.838 T:1397744352 INFO: ffmpeg[534FE2E0]: Duration: N/A, bitrate: N/A
6572019-11-09 16:44:47.838 T:1397744352 INFO: ffmpeg[534FE2E0]: Stream #0:0: Video: mjpeg, yuvj420p(pc, bt470bg/unknown/unknown), 2560x1920, 25 tbr, 25 tbn, 25 tbc
6582019-11-09 16:44:47.838 T:1397744352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
6592019-11-09 16:44:47.838 T:1397744352 NOTICE: Opening stream: 0 source: 256
6602019-11-09 16:44:47.838 T:1397744352 NOTICE: Creating video codec with codec id: 7
6612019-11-09 16:44:47.842 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x6e72fb30 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV)
6622019-11-09 16:44:47.847 T:1397744352 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 7
6632019-11-09 16:44:47.849 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x6e7a83a0 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
6642019-11-09 16:44:47.849 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x604d770 for port vc.ril.video_decode:out:0(I420)
6652019-11-09 16:44:47.850 T:1397744352 NOTICE: Creating video thread
6662019-11-09 16:44:47.850 T:1389351648 DEBUG: Thread VideoPlayerVideo start, auto delete: false
6672019-11-09 16:44:47.850 T:1389351648 NOTICE: running thread: video_thread
6682019-11-09 16:44:47.850 T:1389351648 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
6692019-11-09 16:44:47.850 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
6702019-11-09 16:44:47.851 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
6712019-11-09 16:44:47.851 T:1397744352 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0
6722019-11-09 16:44:47.854 T:1389351648 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
6732019-11-09 16:44:47.855 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
6742019-11-09 16:44:47.855 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
6752019-11-09 16:44:47.855 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 0
6762019-11-09 16:44:47.855 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
6772019-11-09 16:44:47.855 T:1397744352 NOTICE: CVideoPlayer::OnExit()
6782019-11-09 16:44:47.855 T:1397744352 NOTICE: VideoPlayer: eof, waiting for queues to empty
6792019-11-09 16:44:47.855 T:1397744352 DEBUG: CVideoPlayer: updating file item stream details with current streams
6802019-11-09 16:44:47.855 T:1397744352 NOTICE: Closing stream player 2
6812019-11-09 16:44:47.855 T:1397744352 NOTICE: waiting for video thread to exit
6822019-11-09 16:44:47.862 T:1389351648 NOTICE: thread end: video_thread
6832019-11-09 16:44:47.862 T:1389351648 DEBUG: Thread VideoPlayerVideo 1389351648 terminating
6842019-11-09 16:44:47.862 T:1397744352 NOTICE: deleting video codec
6852019-11-09 16:44:47.924 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x6e72fb30 for port vc.ril.video_decode:out:0(OPQV)
6862019-11-09 16:44:47.926 T:1444922080 DEBUG: Thread FileCache 1444922080 terminating
6872019-11-09 16:44:47.926 T:1397744352 DEBUG: Thread VideoPlayer 1397744352 terminating
6882019-11-09 16:44:47.934 T:1332511456 DEBUG: OnPlayBackEnded: CApplication::OnPlayBackEnded
6892019-11-09 16:44:47.944 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
6902019-11-09 16:44:47.944 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
6912019-11-09 16:44:47.955 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
6922019-11-09 16:44:47.955 T:1915319856 DEBUG: DeleteRenderer - deleting renderer
6932019-11-09 16:44:47.955 T:1915319856 DEBUG: CMMALRenderer::~CMMALRenderer
6942019-11-09 16:44:47.955 T:1915319856 DEBUG: CMMALRenderer::UnInitMMAL
6952019-11-09 16:44:47.955 T:1351590624 DEBUG: CMMALRenderer::Run - stopping
6962019-11-09 16:44:47.955 T:1351590624 DEBUG: Thread MMALProcess 1351590624 terminating
6972019-11-09 16:44:47.955 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
6982019-11-09 16:44:47.955 T:1915319856 NOTICE: VideoPlayer: finished waiting
6992019-11-09 16:44:47.955 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
7002019-11-09 16:44:47.955 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
7012019-11-09 16:44:47.955 T:1915319856 NOTICE: VideoPlayer: finished waiting
7022019-11-09 16:44:47.958 T:1915319856 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
7032019-11-09 16:44:47.958 T:1915319856 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x6e7a83a0 for port vc.ril.video_decode:out:0(I420)
7042019-11-09 16:44:47.970 T:1915319856 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
7052019-11-09 16:44:48.012 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnStop Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'032'}, u'end': True}
7062019-11-09 16:44:48.013 T:1690739424 DEBUG: PLEX.kodimonitor: playback_cleanup called. Active players: set([])
7072019-11-09 16:44:48.013 T:1690739424 DEBUG: PLEX.kodimonitor: Finished PKC playback cleanup
7082019-11-09 16:44:48.505 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
7092019-11-09 16:44:48.506 T:1359983328 DEBUG: Thread Timer start, auto delete: false
7102019-11-09 16:44:48.509 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
7112019-11-09 16:44:48.509 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
7122019-11-09 16:44:48.516 T:1690739424 DEBUG: PLEX.kodimonitor: Kodi playing item properties: {u'title': u'', u'type': u'unknown', u'file': u'', u'label': u''}
7132019-11-09 16:44:48.517 T:1690739424 DEBUG: PLEX.kodimonitor: No Plex id obtained - aborting playback report
7142019-11-09 16:44:48.529 T:1766748896 DEBUG: CurlFile::Open(0x6abf4978) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100
7152019-11-09 16:44:48.560 T:1332511456 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/100/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
7162019-11-09 16:44:48.561 T:1332511456 DEBUG: CFileCache::Open - opening <photo/:/transcode> using cache
7172019-11-09 16:44:48.562 T:1332511456 DEBUG: CurlFile::Open(0x6d59a920) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/100/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
7182019-11-09 16:44:48.567 T:1351590624 DEBUG: Thread FileCache start, auto delete: false
7192019-11-09 16:44:48.575 T:1351590624 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
7202019-11-09 16:44:48.627 T:1359983328 DEBUG: Thread Timer 1359983328 terminating
7212019-11-09 16:44:48.628 T:1332511456 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/100/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
7222019-11-09 16:44:48.628 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x68901138
7232019-11-09 16:44:48.629 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(1680960), nBufferAlignment(16)
7242019-11-09 16:44:48.645 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
7252019-11-09 16:44:48.645 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x5eafd8f8
7262019-11-09 16:44:48.647 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x5583bf58
7272019-11-09 16:44:48.649 T:1766748896 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(391680) nBufferAlignment(16)
7282019-11-09 16:44:48.687 T:1351590624 DEBUG: Thread FileCache 1351590624 terminating
7292019-11-09 16:44:48.687 T:1332511456 DEBUG: Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/100/thumb/1573154579&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx' to '2/267091f3.jpg':
7302019-11-09 16:44:48.687 T:1332511456 DEBUG: cached image 'special://masterprofile/Thumbnails/2/267091f3.jpg' size 683x512
7312019-11-09 16:44:48.692 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x6d5869d8
7322019-11-09 16:44:48.695 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_encode) - port(340), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(1409024), nBufferAlignment(16)
7332019-11-09 16:44:48.697 T:1332511456 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(349696) nBufferAlignment(16)
7342019-11-09 16:44:48.707 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
7352019-11-09 16:44:48.707 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
7362019-11-09 16:44:48.708 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
7372019-11-09 16:44:48.974 T:1766748896 DEBUG: COMXReEnc::ReEncode : https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100 2560x1920 -> 720x540
7382019-11-09 16:44:48.979 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x68901138
7392019-11-09 16:44:48.980 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x5eafd8f8
7402019-11-09 16:44:48.981 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x5583bf58
7412019-11-09 16:44:48.982 T:1332511456 DEBUG: COMXImageEnc::CreateThumbnailFromSurface : special://masterprofile/Thumbnails/2/267091f3.jpg width 683 height 512
7422019-11-09 16:44:48.990 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x6d5869d8
7432019-11-09 16:44:48.994 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x5eafd8f8
7442019-11-09 16:44:48.994 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(176784), nBufferAlignment(16)
7452019-11-09 16:44:48.997 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x68901138
7462019-11-09 16:44:48.998 T:1766748896 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
7472019-11-09 16:44:49.021 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x5eafd8f8
7482019-11-09 16:44:49.021 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x68901138
7492019-11-09 16:44:49.022 T:1766748896 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/7/75df8cf8.jpg 720x540
7502019-11-09 16:44:49.022 T:1766748896 DEBUG: Fast Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100' to '7/75df8cf8.jpg': 0x537f664
7512019-11-09 16:44:49.067 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
7522019-11-09 16:44:49.067 T:1351590624 DEBUG: Thread Timer start, auto delete: false
7532019-11-09 16:44:49.092 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
7542019-11-09 16:44:49.169 T:1351590624 DEBUG: Thread Timer 1351590624 terminating
7552019-11-09 16:44:49.176 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
7562019-11-09 16:44:49.176 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
7572019-11-09 16:44:49.179 T:1915319856 DEBUG: OnPlayMedia https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100
7582019-11-09 16:44:49.180 T:1351590624 DEBUG: Thread GUIDialogCache start, auto delete: true
7592019-11-09 16:44:49.184 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100 -> image/jpeg
7602019-11-09 16:44:49.190 T:1351590624 DEBUG: Thread GUIDialogCache 1351590624 terminating (autodelete)
7612019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100)
7622019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
7632019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
7642019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
7652019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
7662019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
7672019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
7682019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
7692019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
7702019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
7712019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
7722019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
7732019-11-09 16:44:49.196 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
7742019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
7752019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
7762019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
7772019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
7782019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
7792019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
7802019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
7812019-11-09 16:44:49.197 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
7822019-11-09 16:44:49.200 T:1915319856 DEBUG: CMMALPool::CMMALPool Created pool 0x6048920 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
7832019-11-09 16:44:49.205 T:1915319856 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
7842019-11-09 16:44:49.205 T:1915319856 NOTICE: VideoPlayer::OpenFile: https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100
7852019-11-09 16:44:49.210 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100 -> image/jpeg
7862019-11-09 16:44:49.211 T:1915319856 DEBUG: CMMALRenderer::CMMALRenderer
7872019-11-09 16:44:49.211 T:1359983328 DEBUG: Thread MMALProcess start, auto delete: false
7882019-11-09 16:44:49.211 T:1397744352 DEBUG: Thread VideoPlayer start, auto delete: false
7892019-11-09 16:44:49.211 T:1915319856 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
7902019-11-09 16:44:49.211 T:1359983328 DEBUG: CMMALRenderer::Run - starting
7912019-11-09 16:44:49.211 T:1397744352 NOTICE: Creating InputStream
7922019-11-09 16:44:49.212 T:1397744352 DEBUG: CurlFile::Open(0x534fce90) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100
7932019-11-09 16:44:49.219 T:1332511456 DEBUG: Loading settings for https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100
7942019-11-09 16:44:49.226 T:1397744352 DEBUG: CFileCache::Open - opening <library/parts/96/1573154055/file.jpg> using cache
7952019-11-09 16:44:49.227 T:1397744352 DEBUG: CurlFile::Open(0x70fd0848) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100
7962019-11-09 16:44:49.229 T:1915319856 DEBUG: CVideoGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100)
7972019-11-09 16:44:49.229 T:1915319856 DEBUG: CPlayerGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100)
7982019-11-09 16:44:49.229 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
7992019-11-09 16:44:49.229 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
8002019-11-09 16:44:49.239 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
8012019-11-09 16:44:49.239 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
8022019-11-09 16:44:49.321 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Info.OnChanged Data: None
8032019-11-09 16:44:49.322 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnPlay Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'033'}, u'player': {u'playerid': -1, u'speed': 1}}
8042019-11-09 16:44:49.328 T:1397744352 NOTICE: Creating Demuxer
8052019-11-09 16:44:49.328 T:1599070944 DEBUG: Thread FileCache start, auto delete: false
8062019-11-09 16:44:49.329 T:1915319856 DEBUG: ------ Window Init (DialogBusy.xml) ------
8072019-11-09 16:44:49.444 T:1599070944 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
8082019-11-09 16:44:49.550 T:1690739424 DEBUG: PLEX.kodimonitor: Detected position 0 for {'playlistid': 1, 'id': None, 'version': None, 'type': 'video', 'selectedItemID': None, 'selectedItemOffset': None, 'shuffled': 0, 'repeat': 0, 'kodi_playlist_playback': False, 'pkc_edit': False, 'items': []}
8092019-11-09 16:44:49.551 T:1690739424 DEBUG: PLEX.kodimonitor: Position 0 not in PKC playqueue yet
8102019-11-09 16:44:49.551 T:1690739424 DEBUG: PLEX.kodimonitor: Need to initialize Plex and PKC playqueue
8112019-11-09 16:44:49.763 T:1397744352 DEBUG: ffmpeg[534FE2E0]: Format jpeg_pipe detected only with low score of 25, misdetection possible!
8122019-11-09 16:44:49.763 T:1397744352 DEBUG: Open - probing detected format [jpeg_pipe]
8132019-11-09 16:44:49.763 T:1397744352 DEBUG: Open - avformat_find_stream_info starting
8142019-11-09 16:44:49.826 T:1397744352 DEBUG: Open - av_find_stream_info finished
8152019-11-09 16:44:49.826 T:1397744352 INFO: ffmpeg[534FE2E0]: Input #0, jpeg_pipe, from 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/96/1573154055/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=100':
8162019-11-09 16:44:49.826 T:1397744352 INFO: ffmpeg[534FE2E0]: Duration: N/A, bitrate: N/A
8172019-11-09 16:44:49.827 T:1397744352 INFO: ffmpeg[534FE2E0]: Stream #0:0: Video: mjpeg, yuvj420p(pc, bt470bg/unknown/unknown), 2560x1920, 25 tbr, 25 tbn, 25 tbc
8182019-11-09 16:44:49.827 T:1397744352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
8192019-11-09 16:44:49.827 T:1397744352 NOTICE: Opening stream: 0 source: 256
8202019-11-09 16:44:49.827 T:1397744352 NOTICE: Creating video codec with codec id: 7
8212019-11-09 16:44:49.830 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x70fc8220 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV)
8222019-11-09 16:44:49.835 T:1397744352 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 7
8232019-11-09 16:44:49.836 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x55407b50 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
8242019-11-09 16:44:49.836 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x6048920 for port vc.ril.video_decode:out:0(I420)
8252019-11-09 16:44:49.837 T:1397744352 NOTICE: Creating video thread
8262019-11-09 16:44:49.837 T:1351590624 DEBUG: Thread VideoPlayerVideo start, auto delete: false
8272019-11-09 16:44:49.837 T:1351590624 NOTICE: running thread: video_thread
8282019-11-09 16:44:49.837 T:1351590624 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
8292019-11-09 16:44:49.838 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
8302019-11-09 16:44:49.838 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
8312019-11-09 16:44:49.838 T:1397744352 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0
8322019-11-09 16:44:49.841 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
8332019-11-09 16:44:49.841 T:1351590624 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
8342019-11-09 16:44:49.841 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
8352019-11-09 16:44:49.841 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 0
8362019-11-09 16:44:49.841 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
8372019-11-09 16:44:49.842 T:1397744352 NOTICE: CVideoPlayer::OnExit()
8382019-11-09 16:44:49.842 T:1397744352 NOTICE: VideoPlayer: eof, waiting for queues to empty
8392019-11-09 16:44:49.842 T:1397744352 DEBUG: CVideoPlayer: updating file item stream details with current streams
8402019-11-09 16:44:49.842 T:1397744352 NOTICE: Closing stream player 2
8412019-11-09 16:44:49.842 T:1397744352 NOTICE: waiting for video thread to exit
8422019-11-09 16:44:49.846 T:1351590624 NOTICE: thread end: video_thread
8432019-11-09 16:44:49.847 T:1351590624 DEBUG: Thread VideoPlayerVideo 1351590624 terminating
8442019-11-09 16:44:49.847 T:1397744352 NOTICE: deleting video codec
8452019-11-09 16:44:49.908 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x70fc8220 for port vc.ril.video_decode:out:0(OPQV)
8462019-11-09 16:44:49.911 T:1599070944 DEBUG: Thread FileCache 1599070944 terminating
8472019-11-09 16:44:49.911 T:1397744352 DEBUG: Thread VideoPlayer 1397744352 terminating
8482019-11-09 16:44:49.919 T:1332511456 DEBUG: OnPlayBackEnded: CApplication::OnPlayBackEnded
8492019-11-09 16:44:49.928 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
8502019-11-09 16:44:49.929 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
8512019-11-09 16:44:49.952 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnStop Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'033'}, u'end': True}
8522019-11-09 16:44:49.952 T:1690739424 DEBUG: PLEX.kodimonitor: playback_cleanup called. Active players: set([])
8532019-11-09 16:44:49.953 T:1690739424 DEBUG: PLEX.kodimonitor: Finished PKC playback cleanup
8542019-11-09 16:44:49.986 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
8552019-11-09 16:44:49.986 T:1915319856 DEBUG: DeleteRenderer - deleting renderer
8562019-11-09 16:44:49.986 T:1915319856 DEBUG: CMMALRenderer::~CMMALRenderer
8572019-11-09 16:44:49.986 T:1915319856 DEBUG: CMMALRenderer::UnInitMMAL
8582019-11-09 16:44:49.987 T:1359983328 DEBUG: CMMALRenderer::Run - stopping
8592019-11-09 16:44:49.987 T:1359983328 DEBUG: Thread MMALProcess 1359983328 terminating
8602019-11-09 16:44:49.987 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
8612019-11-09 16:44:49.987 T:1915319856 NOTICE: VideoPlayer: finished waiting
8622019-11-09 16:44:49.987 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
8632019-11-09 16:44:49.987 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
8642019-11-09 16:44:49.987 T:1915319856 NOTICE: VideoPlayer: finished waiting
8652019-11-09 16:44:49.989 T:1915319856 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
8662019-11-09 16:44:49.989 T:1915319856 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x55407b50 for port vc.ril.video_decode:out:0(I420)
8672019-11-09 16:44:50.001 T:1915319856 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
8682019-11-09 16:44:50.423 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
8692019-11-09 16:44:50.423 T:1368376032 DEBUG: Thread Timer start, auto delete: false
8702019-11-09 16:44:50.426 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
8712019-11-09 16:44:50.426 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
8722019-11-09 16:44:50.443 T:1766748896 DEBUG: CurlFile::Open(0x6abf4978) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101
8732019-11-09 16:44:50.476 T:1332511456 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/101/thumb/1573154580&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
8742019-11-09 16:44:50.477 T:1332511456 DEBUG: CFileCache::Open - opening <photo/:/transcode> using cache
8752019-11-09 16:44:50.478 T:1332511456 DEBUG: CurlFile::Open(0x6d59a868) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/101/thumb/1573154580&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
8762019-11-09 16:44:50.482 T:1359983328 DEBUG: Thread FileCache start, auto delete: false
8772019-11-09 16:44:50.488 T:1359983328 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
8782019-11-09 16:44:50.523 T:1368376032 DEBUG: Thread Timer 1368376032 terminating
8792019-11-09 16:44:50.526 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
8802019-11-09 16:44:50.541 T:1332511456 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/101/thumb/1573154580&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -> image/jpeg
8812019-11-09 16:44:50.553 T:1690739424 DEBUG: PLEX.kodimonitor: Kodi playing item properties: {u'title': u'', u'type': u'unknown', u'file': u'', u'label': u''}
8822019-11-09 16:44:50.553 T:1690739424 DEBUG: PLEX.kodimonitor: No Plex id obtained - aborting playback report
8832019-11-09 16:44:50.561 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x5eafd8f8
8842019-11-09 16:44:50.562 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(1703392), nBufferAlignment(16)
8852019-11-09 16:44:50.577 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x5c93c6a8
8862019-11-09 16:44:50.579 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x6fb31498
8872019-11-09 16:44:50.581 T:1766748896 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(391680) nBufferAlignment(16)
8882019-11-09 16:44:50.599 T:1359983328 DEBUG: Thread FileCache 1359983328 terminating
8892019-11-09 16:44:50.600 T:1332511456 DEBUG: Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/photo/:/transcode?width=1920&height=1920&minSize=1&upscale=0&url=/library/metadata/101/thumb/1573154580&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx' to '7/776df06d.jpg':
8902019-11-09 16:44:50.600 T:1332511456 DEBUG: cached image 'special://masterprofile/Thumbnails/7/776df06d.jpg' size 683x512
8912019-11-09 16:44:50.606 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_encode input port 340 output port 341 m_handle 0x6d5db448
8922019-11-09 16:44:50.610 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_encode) - port(340), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(1409024), nBufferAlignment(16)
8932019-11-09 16:44:50.613 T:1332511456 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.image_encode) - port(341), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(349696) nBufferAlignment(16)
8942019-11-09 16:44:50.889 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
8952019-11-09 16:44:50.890 T:1359983328 DEBUG: Thread Timer start, auto delete: false
8962019-11-09 16:44:50.926 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
8972019-11-09 16:44:50.965 T:1332511456 DEBUG: COMXImageEnc::CreateThumbnailFromSurface : special://masterprofile/Thumbnails/7/776df06d.jpg width 683 height 512
8982019-11-09 16:44:50.979 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x6d5db448
8992019-11-09 16:44:50.982 T:1766748896 DEBUG: COMXReEnc::ReEncode : https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101 2560x1920 -> 720x540
9002019-11-09 16:44:50.986 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x5eafd8f8
9012019-11-09 16:44:50.988 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x5c93c6a8
9022019-11-09 16:44:50.991 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_encode handle 0x6fb31498
9032019-11-09 16:44:51.008 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x5eafd8f8
9042019-11-09 16:44:51.009 T:1359983328 DEBUG: Thread Timer 1359983328 terminating
9052019-11-09 16:44:51.010 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(182800), nBufferAlignment(16)
9062019-11-09 16:44:51.016 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x6fb31498
9072019-11-09 16:44:51.018 T:1766748896 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
9082019-11-09 16:44:51.037 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
9092019-11-09 16:44:51.037 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
9102019-11-09 16:44:51.044 T:1915319856 DEBUG: OnPlayMedia https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101
9112019-11-09 16:44:51.045 T:1359983328 DEBUG: Thread GUIDialogCache start, auto delete: true
9122019-11-09 16:44:51.045 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x5eafd8f8
9132019-11-09 16:44:51.048 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x6fb31498
9142019-11-09 16:44:51.048 T:1766748896 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/b/b6182244.jpg 720x540
9152019-11-09 16:44:51.048 T:1766748896 DEBUG: Fast Caching image 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101' to 'b/b6182244.jpg': 0x537f664
9162019-11-09 16:44:51.050 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101 -> image/jpeg
9172019-11-09 16:44:51.055 T:1359983328 DEBUG: Thread GUIDialogCache 1359983328 terminating (autodelete)
9182019-11-09 16:44:51.074 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101)
9192019-11-09 16:44:51.074 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
9202019-11-09 16:44:51.074 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
9212019-11-09 16:44:51.074 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
9222019-11-09 16:44:51.075 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
9232019-11-09 16:44:51.075 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
9242019-11-09 16:44:51.075 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
9252019-11-09 16:44:51.075 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
9262019-11-09 16:44:51.075 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
9272019-11-09 16:44:51.075 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
9282019-11-09 16:44:51.076 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
9292019-11-09 16:44:51.076 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
9302019-11-09 16:44:51.076 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
9312019-11-09 16:44:51.076 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
9322019-11-09 16:44:51.076 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
9332019-11-09 16:44:51.076 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
9342019-11-09 16:44:51.076 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
9352019-11-09 16:44:51.077 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
9362019-11-09 16:44:51.077 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
9372019-11-09 16:44:51.077 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
9382019-11-09 16:44:51.077 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
9392019-11-09 16:44:51.080 T:1915319856 DEBUG: CMMALPool::CMMALPool Created pool 0x566c5c0 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
9402019-11-09 16:44:51.089 T:1915319856 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
9412019-11-09 16:44:51.089 T:1915319856 NOTICE: VideoPlayer::OpenFile: https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101
9422019-11-09 16:44:51.094 T:1915319856 DEBUG: CCurlFile::GetMimeType - https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101 -> image/jpeg
9432019-11-09 16:44:51.095 T:1915319856 DEBUG: CMMALRenderer::CMMALRenderer
9442019-11-09 16:44:51.095 T:1368376032 DEBUG: Thread MMALProcess start, auto delete: false
9452019-11-09 16:44:51.095 T:1915319856 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
9462019-11-09 16:44:51.095 T:1397744352 DEBUG: Thread VideoPlayer start, auto delete: false
9472019-11-09 16:44:51.095 T:1368376032 DEBUG: CMMALRenderer::Run - starting
9482019-11-09 16:44:51.098 T:1397744352 NOTICE: Creating InputStream
9492019-11-09 16:44:51.100 T:1397744352 DEBUG: CurlFile::Open(0x534fce90) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101
9502019-11-09 16:44:51.113 T:1332511456 DEBUG: Loading settings for https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101
9512019-11-09 16:44:51.121 T:1397744352 DEBUG: CFileCache::Open - opening <library/parts/97/1573154056/file.jpg> using cache
9522019-11-09 16:44:51.122 T:1397744352 DEBUG: CurlFile::Open(0x6d566600) https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101
9532019-11-09 16:44:51.122 T:1915319856 DEBUG: CVideoGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101)
9542019-11-09 16:44:51.123 T:1915319856 DEBUG: CPlayerGUIInfo::InitCurrentItem(https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101)
9552019-11-09 16:44:51.123 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
9562019-11-09 16:44:51.123 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
9572019-11-09 16:44:51.144 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
9582019-11-09 16:44:51.145 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
9592019-11-09 16:44:51.158 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Info.OnChanged Data: None
9602019-11-09 16:44:51.159 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnPlay Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'034'}, u'player': {u'playerid': -1, u'speed': 1}}
9612019-11-09 16:44:51.162 T:1690739424 DEBUG: PLEX.kodimonitor: Detected position 0 for {'playlistid': 1, 'id': None, 'version': None, 'type': 'video', 'selectedItemID': None, 'selectedItemOffset': None, 'shuffled': 0, 'repeat': 0, 'kodi_playlist_playback': False, 'pkc_edit': False, 'items': []}
9622019-11-09 16:44:51.163 T:1690739424 DEBUG: PLEX.kodimonitor: Position 0 not in PKC playqueue yet
9632019-11-09 16:44:51.164 T:1690739424 DEBUG: PLEX.kodimonitor: Need to initialize Plex and PKC playqueue
9642019-11-09 16:44:51.201 T:1599070944 DEBUG: Thread FileCache start, auto delete: false
9652019-11-09 16:44:51.202 T:1397744352 NOTICE: Creating Demuxer
9662019-11-09 16:44:51.231 T:1915319856 DEBUG: ------ Window Init (DialogBusy.xml) ------
9672019-11-09 16:44:51.274 T:1599070944 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
9682019-11-09 16:44:51.627 T:1397744352 DEBUG: ffmpeg[534FE2E0]: Format jpeg_pipe detected only with low score of 25, misdetection possible!
9692019-11-09 16:44:51.627 T:1397744352 DEBUG: Open - probing detected format [jpeg_pipe]
9702019-11-09 16:44:51.627 T:1397744352 DEBUG: Open - avformat_find_stream_info starting
9712019-11-09 16:44:51.692 T:1397744352 DEBUG: Open - av_find_stream_info finished
9722019-11-09 16:44:51.693 T:1397744352 INFO: ffmpeg[534FE2E0]: Input #0, jpeg_pipe, from 'https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct:32400/library/parts/97/1573154056/file.jpg?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&plex_id=101':
9732019-11-09 16:44:51.693 T:1397744352 INFO: ffmpeg[534FE2E0]: Duration: N/A, bitrate: N/A
9742019-11-09 16:44:51.693 T:1397744352 INFO: ffmpeg[534FE2E0]: Stream #0:0: Video: mjpeg, yuvj420p(pc, bt470bg/unknown/unknown), 2560x1920, 25 tbr, 25 tbn, 25 tbc
9752019-11-09 16:44:51.693 T:1397744352 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
9762019-11-09 16:44:51.693 T:1397744352 NOTICE: Opening stream: 0 source: 256
9772019-11-09 16:44:51.693 T:1397744352 NOTICE: Creating video codec with codec id: 7
9782019-11-09 16:44:51.697 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x6d5ae550 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV)
9792019-11-09 16:44:51.701 T:1397744352 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 7
9802019-11-09 16:44:51.703 T:1397744352 DEBUG: CMMALPool::CMMALPool Created pool 0x54e040d0 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
9812019-11-09 16:44:51.703 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x566c5c0 for port vc.ril.video_decode:out:0(I420)
9822019-11-09 16:44:51.703 T:1397744352 NOTICE: Creating video thread
9832019-11-09 16:44:51.703 T:1359983328 DEBUG: Thread VideoPlayerVideo start, auto delete: false
9842019-11-09 16:44:51.703 T:1359983328 NOTICE: running thread: video_thread
9852019-11-09 16:44:51.703 T:1359983328 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
9862019-11-09 16:44:51.704 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
9872019-11-09 16:44:51.704 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
9882019-11-09 16:44:51.704 T:1397744352 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0
9892019-11-09 16:44:51.708 T:1359983328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
9902019-11-09 16:44:51.708 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 1
9912019-11-09 16:44:51.708 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
9922019-11-09 16:44:51.708 T:1397744352 DEBUG: CVideoPlayer::SetCaching - caching state 0
9932019-11-09 16:44:51.709 T:1397744352 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
9942019-11-09 16:44:51.709 T:1397744352 NOTICE: CVideoPlayer::OnExit()
9952019-11-09 16:44:51.709 T:1397744352 NOTICE: VideoPlayer: eof, waiting for queues to empty
9962019-11-09 16:44:51.709 T:1397744352 DEBUG: CVideoPlayer: updating file item stream details with current streams
9972019-11-09 16:44:51.709 T:1397744352 NOTICE: Closing stream player 2
9982019-11-09 16:44:51.709 T:1397744352 NOTICE: waiting for video thread to exit
9992019-11-09 16:44:51.714 T:1359983328 NOTICE: thread end: video_thread
10002019-11-09 16:44:51.714 T:1359983328 DEBUG: Thread VideoPlayerVideo 1359983328 terminating
10012019-11-09 16:44:51.714 T:1397744352 NOTICE: deleting video codec
10022019-11-09 16:44:51.772 T:1397744352 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x6d5ae550 for port vc.ril.video_decode:out:0(OPQV)
10032019-11-09 16:44:51.775 T:1599070944 DEBUG: Thread FileCache 1599070944 terminating
10042019-11-09 16:44:51.775 T:1397744352 DEBUG: Thread VideoPlayer 1397744352 terminating
10052019-11-09 16:44:51.784 T:1332511456 DEBUG: OnPlayBackEnded: CApplication::OnPlayBackEnded
10062019-11-09 16:44:51.793 T:1915306720 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
10072019-11-09 16:44:51.793 T:1915306720 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
10082019-11-09 16:44:51.865 T:1690739424 DEBUG: PLEX.kodimonitor: Method: Player.OnStop Data: {u'item': {u'year': 2006, u'type': u'movie', u'title': u'034'}, u'end': True}
10092019-11-09 16:44:51.865 T:1690739424 DEBUG: PLEX.kodimonitor: playback_cleanup called. Active players: set([])
10102019-11-09 16:44:51.866 T:1690739424 DEBUG: PLEX.kodimonitor: Finished PKC playback cleanup
10112019-11-09 16:44:51.883 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
10122019-11-09 16:44:51.883 T:1915319856 DEBUG: DeleteRenderer - deleting renderer
10132019-11-09 16:44:51.883 T:1915319856 DEBUG: CMMALRenderer::~CMMALRenderer
10142019-11-09 16:44:51.883 T:1915319856 DEBUG: CMMALRenderer::UnInitMMAL
10152019-11-09 16:44:51.883 T:1368376032 DEBUG: CMMALRenderer::Run - stopping
10162019-11-09 16:44:51.883 T:1368376032 DEBUG: Thread MMALProcess 1368376032 terminating
10172019-11-09 16:44:51.883 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
10182019-11-09 16:44:51.883 T:1915319856 NOTICE: VideoPlayer: finished waiting
10192019-11-09 16:44:51.883 T:1915319856 NOTICE: CVideoPlayer::CloseFile()
10202019-11-09 16:44:51.883 T:1915319856 NOTICE: VideoPlayer: waiting for threads to exit
10212019-11-09 16:44:51.883 T:1915319856 NOTICE: VideoPlayer: finished waiting
10222019-11-09 16:44:51.885 T:1915319856 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
10232019-11-09 16:44:51.886 T:1915319856 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x54e040d0 for port vc.ril.video_decode:out:0(I420)
10242019-11-09 16:44:51.899 T:1915319856 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
10252019-11-09 16:44:52.165 T:1690739424 DEBUG: PLEX.kodimonitor: Kodi playing item properties: {u'title': u'', u'type': u'unknown', u'file': u'', u'label': u''}
10262019-11-09 16:44:52.166 T:1690739424 DEBUG: PLEX.kodimonitor: No Plex id obtained - aborting playback report
10272019-11-09 16:44:53.712 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
10282019-11-09 16:44:53.712 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
10292019-11-09 16:44:53.713 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
10302019-11-09 16:44:54.525 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
10312019-11-09 16:44:54.526 T:1351590624 DEBUG: Thread Timer start, auto delete: false
10322019-11-09 16:44:54.533 T:1915319856 DEBUG: Keyboard: scancode: 0x0e, sym: 0x0008, unicode: 0x0008, modifier: 0x0
10332019-11-09 16:44:54.627 T:1351590624 DEBUG: Thread Timer 1351590624 terminating
10342019-11-09 16:44:54.643 T:1915319856 DEBUG: Keyboard: scancode: 0x0e, sym: 0x0008, unicode: 0x0008, modifier: 0x0
10352019-11-09 16:44:54.643 T:1915319856 DEBUG: HandleKey: backspace (0xf008) pressed, action is Back
10362019-11-09 16:44:54.644 T:1915319856 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fsections%2f3%2fall&mode=browseplex)
10372019-11-09 16:44:54.644 T:1915319856 DEBUG: ParentPath = [plugin://plugin.video.plexkodiconnect/?key=%2flibrary%2fsections%2f3&mode=browseplex§ion_id=3&synched=false]
10382019-11-09 16:44:54.662 T:1915319856 DEBUG: Loading items: 103, directory: plugin://plugin.video.plexkodiconnect/?id=3&key=%2flibrary%2fsections%2f3%2fall&mode=browseplex sort method: 0, ascending: false
10392019-11-09 16:44:54.664 T:1332511456 DEBUG: CAddonDatabase::SetLastUsed[plugin.video.plexkodiconnect] took 19 ms
10402019-11-09 16:44:54.717 T:1351590624 DEBUG: Thread BackgroundLoader start, auto delete: false
10412019-11-09 16:44:54.726 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x6d5db448
10422019-11-09 16:44:54.727 T:1332511456 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(122256), nBufferAlignment(16)
10432019-11-09 16:44:54.731 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x5eafd8f8
10442019-11-09 16:44:54.732 T:1766748896 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(89072), nBufferAlignment(16)
10452019-11-09 16:44:54.732 T:1332511456 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x6d5ae5f8
10462019-11-09 16:44:54.733 T:1332511456 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
10472019-11-09 16:44:54.743 T:1766748896 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x5583bf58
10482019-11-09 16:44:54.743 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x6d5db448
10492019-11-09 16:44:54.743 T:1766748896 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
10502019-11-09 16:44:54.744 T:1332511456 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x6d5ae5f8
10512019-11-09 16:44:54.744 T:1351590624 DEBUG: Thread BackgroundLoader 1351590624 terminating
10522019-11-09 16:44:54.744 T:1332511456 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/d/d51fc4ec.jpg 683x512
10532019-11-09 16:44:54.755 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x5eafd8f8
10542019-11-09 16:44:54.757 T:1766748896 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x5583bf58
10552019-11-09 16:44:54.757 T:1766748896 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/9/9a7f7368.jpg 683x512
10562019-11-09 16:44:56.269 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
10572019-11-09 16:44:56.270 T:1351590624 DEBUG: Thread Timer start, auto delete: false
10582019-11-09 16:44:56.293 T:1915319856 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x001b, modifier: 0x0
10592019-11-09 16:44:56.431 T:1351590624 DEBUG: Thread Timer 1351590624 terminating
10602019-11-09 16:44:56.443 T:1915319856 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x001b, modifier: 0x0
10612019-11-09 16:44:56.443 T:1915319856 DEBUG: HandleKey: escape (0xf01b) pressed, action is PreviousMenu
10622019-11-09 16:44:56.443 T:1915319856 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
10632019-11-09 16:44:56.803 T:1915319856 DEBUG: ------ Window Deinit (MyPics.xml) ------
10642019-11-09 16:44:56.803 T:1915319856 DEBUG: FreeVisualisation() done
10652019-11-09 16:44:56.805 T:1915319856 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
10662019-11-09 16:44:56.805 T:1915319856 DEBUG: ------ Window Init (Home.xml) ------
10672019-11-09 16:44:56.806 T:1915319856 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing..
10682019-11-09 16:44:56.806 T:1915319856 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
10692019-11-09 16:44:56.811 T:1915319856 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
10702019-11-09 16:44:56.812 T:1915319856 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
10712019-11-09 16:44:56.812 T:1915319856 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
10722019-11-09 16:44:56.812 T:1915319856 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
10732019-11-09 16:44:56.813 T:1915319856 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing..
10742019-11-09 16:44:56.813 T:1915319856 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
10752019-11-09 16:44:56.813 T:1915319856 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
10762019-11-09 16:44:56.813 T:1915319856 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
10772019-11-09 16:44:56.813 T:1915319856 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
10782019-11-09 16:44:56.813 T:1915319856 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
10792019-11-09 16:44:56.813 T:1915319856 DEBUG: CDirectoryProvider[addons://]: refreshing..
10802019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
10812019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
10822019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
10832019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
10842019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
10852019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[library://video/]: refreshing..
10862019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
10872019-11-09 16:44:56.814 T:1915319856 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
10882019-11-09 16:44:56.815 T:1915319856 DEBUG: CDirectoryProvider[favourites://]: refreshing..
10892019-11-09 16:44:56.816 T:1915319856 DEBUG: CDirectoryProvider[sources://pictures/]: refreshing..
10902019-11-09 16:44:56.816 T:1915319856 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
10912019-11-09 16:44:56.825 T:1351590624 DEBUG: Thread JobWorker start, auto delete: true
10922019-11-09 16:44:56.852 T:1368376032 DEBUG: Previous line repeats 1 times.
10932019-11-09 16:44:56.852 T:1368376032 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x71985ea8
10942019-11-09 16:44:56.852 T:1368376032 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(172672), nBufferAlignment(16)
10952019-11-09 16:44:56.855 T:1368376032 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x55215468
10962019-11-09 16:44:56.856 T:1368376032 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
10972019-11-09 16:44:56.879 T:1368376032 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x71985ea8
10982019-11-09 16:44:56.880 T:1368376032 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x55215468
10992019-11-09 16:44:56.880 T:1368376032 DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/c/cc6b24f1.jpg 1280x720
11002019-11-09 16:44:57.003 T:1332511456 DEBUG: Repository add-on repository.xbmc.org uses plain HTTP for add-on downloads in path http://download.osmc.tv/kodi/addons/leia - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
11012019-11-09 16:44:57.060 T:1332511456 DEBUG: CAddonMgr::GetAvailableUpdates took 66 ms
11022019-11-09 16:44:57.098 T:1332511456 DEBUG: Repository add-on repository.xbmc.org uses plain HTTP for add-on downloads in path http://download.osmc.tv/kodi/addons/leia - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
11032019-11-09 16:44:57.179 T:1351590624 DEBUG: Previous line repeats 1 times.
11042019-11-09 16:44:57.180 T:1351590624 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
11052019-11-09 16:44:57.180 T:1351590624 DEBUG: Getting Directory (special://profile/playlists/video)
11062019-11-09 16:44:57.181 T:1351590624 DEBUG: Getting Directory (special://profile/playlists/mixed)
11072019-11-09 16:44:57.182 T:1351590624 DEBUG: CMultiPathDirectory::MergeItems, items = 2
11082019-11-09 16:44:57.182 T:1351590624 DEBUG: Testing path: [000] special://profile/playlists/video/Plex 2.xsp
11092019-11-09 16:44:57.182 T:1351590624 DEBUG: CMultiPathDirectory::MergeItems, items = 2, took 0 ms
11102019-11-09 16:44:57.230 T:1368376032 ERROR: Unable to lookup host: 'folder.jpg'
11112019-11-09 16:44:57.270 T:1368376032 DEBUG: NFS: Context for folder.jpg not open - get a new context.
11122019-11-09 16:44:57.311 T:1368376032 ERROR: NFS: Failed to mount nfs share: (nfs_mount_async failed)
11132019-11-09 16:44:57.341 T:1368376032 ERROR: Unable to lookup host: 'folder.jpg'
11142019-11-09 16:44:58.139 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11152019-11-09 16:44:58.140 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11162019-11-09 16:44:58.176 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
11172019-11-09 16:44:58.176 T:1915319856 DEBUG: HandleKey: left (0xf082) pressed, action is Left
11182019-11-09 16:44:58.299 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11192019-11-09 16:44:58.326 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
11202019-11-09 16:44:58.716 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
11212019-11-09 16:44:58.717 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
11222019-11-09 16:44:58.719 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
11232019-11-09 16:44:59.031 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11242019-11-09 16:44:59.032 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11252019-11-09 16:44:59.038 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11262019-11-09 16:44:59.038 T:1915319856 DEBUG: HandleKey: up (0xf080) pressed, action is Up
11272019-11-09 16:44:59.173 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11282019-11-09 16:44:59.176 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11292019-11-09 16:44:59.531 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11302019-11-09 16:44:59.532 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11312019-11-09 16:44:59.574 T:1915319856 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
11322019-11-09 16:44:59.574 T:1915319856 DEBUG: HandleKey: right (0xf083) pressed, action is Right
11332019-11-09 16:44:59.711 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11342019-11-09 16:44:59.756 T:1915319856 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
11352019-11-09 16:44:59.943 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11362019-11-09 16:44:59.944 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11372019-11-09 16:44:59.959 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11382019-11-09 16:45:00.205 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11392019-11-09 16:45:00.238 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11402019-11-09 16:45:00.238 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
11412019-11-09 16:45:00.238 T:1915319856 DEBUG: Activating window ID: 10004
11422019-11-09 16:45:00.560 T:1915319856 DEBUG: ------ Window Deinit (Home.xml) ------
11432019-11-09 16:45:00.561 T:1915319856 DEBUG: FreeVisualisation() done
11442019-11-09 16:45:00.576 T:1915319856 DEBUG: ------ Window Init (Settings.xml) ------
11452019-11-09 16:45:02.183 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11462019-11-09 16:45:02.184 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11472019-11-09 16:45:02.192 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11482019-11-09 16:45:02.192 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
11492019-11-09 16:45:02.325 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11502019-11-09 16:45:02.326 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11512019-11-09 16:45:03.722 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
11522019-11-09 16:45:03.723 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
11532019-11-09 16:45:03.723 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
11542019-11-09 16:45:03.851 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11552019-11-09 16:45:03.852 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11562019-11-09 16:45:03.876 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11572019-11-09 16:45:03.876 T:1915319856 DEBUG: HandleKey: up (0xf080) pressed, action is Up
11582019-11-09 16:45:03.951 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11592019-11-09 16:45:03.959 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11602019-11-09 16:45:05.087 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11612019-11-09 16:45:05.088 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11622019-11-09 16:45:05.105 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11632019-11-09 16:45:05.229 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11642019-11-09 16:45:05.262 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11652019-11-09 16:45:05.262 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
11662019-11-09 16:45:05.262 T:1915319856 DEBUG: Activating window ID: 10003
11672019-11-09 16:45:05.585 T:1915319856 DEBUG: ------ Window Deinit (Settings.xml) ------
11682019-11-09 16:45:05.586 T:1915319856 DEBUG: FreeVisualisation() done
11692019-11-09 16:45:05.609 T:1915319856 DEBUG: ------ Window Init (FileManager.xml) ------
11702019-11-09 16:45:05.610 T:1397744352 DEBUG: Thread waiting start, auto delete: false
11712019-11-09 16:45:05.612 T:1397744352 DEBUG: Thread waiting 1397744352 terminating
11722019-11-09 16:45:05.969 T:1397744352 DEBUG: Thread waiting start, auto delete: false
11732019-11-09 16:45:05.970 T:1397744352 DEBUG: Thread waiting 1397744352 terminating
11742019-11-09 16:45:06.683 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11752019-11-09 16:45:06.684 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11762019-11-09 16:45:06.690 T:1915319856 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
11772019-11-09 16:45:06.690 T:1915319856 DEBUG: HandleKey: right (0xf083) pressed, action is Right
11782019-11-09 16:45:06.845 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11792019-11-09 16:45:06.886 T:1915319856 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
11802019-11-09 16:45:08.726 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
11812019-11-09 16:45:08.726 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
11822019-11-09 16:45:08.727 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
11832019-11-09 16:45:08.929 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11842019-11-09 16:45:08.930 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11852019-11-09 16:45:08.954 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11862019-11-09 16:45:08.954 T:1915319856 DEBUG: HandleKey: up (0xf080) pressed, action is Up
11872019-11-09 16:45:09.049 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11882019-11-09 16:45:09.052 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11892019-11-09 16:45:09.157 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11902019-11-09 16:45:09.158 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11912019-11-09 16:45:09.176 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11922019-11-09 16:45:09.176 T:1915319856 DEBUG: HandleKey: up (0xf080) pressed, action is Up
11932019-11-09 16:45:09.257 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
11942019-11-09 16:45:09.259 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11952019-11-09 16:45:09.421 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
11962019-11-09 16:45:09.421 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
11972019-11-09 16:45:09.422 T:1397744352 DEBUG: Thread Timer start, auto delete: false
11982019-11-09 16:45:09.422 T:1915319856 DEBUG: HandleKey: up (0xf080) pressed, action is Up
11992019-11-09 16:45:09.541 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12002019-11-09 16:45:09.552 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
12012019-11-09 16:45:09.821 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12022019-11-09 16:45:09.822 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12032019-11-09 16:45:09.838 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
12042019-11-09 16:45:09.838 T:1915319856 DEBUG: HandleKey: up (0xf080) pressed, action is Up
12052019-11-09 16:45:09.943 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12062019-11-09 16:45:09.978 T:1915319856 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
12072019-11-09 16:45:10.547 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12082019-11-09 16:45:10.548 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12092019-11-09 16:45:10.559 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12102019-11-09 16:45:10.559 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
12112019-11-09 16:45:10.687 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12122019-11-09 16:45:10.709 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12132019-11-09 16:45:10.791 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12142019-11-09 16:45:10.792 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12152019-11-09 16:45:10.793 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12162019-11-09 16:45:10.793 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
12172019-11-09 16:45:10.891 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12182019-11-09 16:45:10.893 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12192019-11-09 16:45:11.159 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12202019-11-09 16:45:11.160 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12212019-11-09 16:45:11.172 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
12222019-11-09 16:45:11.299 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12232019-11-09 16:45:11.319 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
12242019-11-09 16:45:11.319 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
12252019-11-09 16:45:11.320 T:1397744352 DEBUG: Thread waiting start, auto delete: false
12262019-11-09 16:45:11.322 T:1397744352 DEBUG: Thread waiting 1397744352 terminating
12272019-11-09 16:45:12.103 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12282019-11-09 16:45:12.103 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12292019-11-09 16:45:12.129 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
12302019-11-09 16:45:12.243 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12312019-11-09 16:45:12.259 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
12322019-11-09 16:45:12.259 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
12332019-11-09 16:45:12.260 T:1397744352 DEBUG: Thread waiting start, auto delete: false
12342019-11-09 16:45:12.261 T:1397744352 DEBUG: Thread waiting 1397744352 terminating
12352019-11-09 16:45:12.721 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12362019-11-09 16:45:12.722 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12372019-11-09 16:45:12.732 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12382019-11-09 16:45:12.732 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
12392019-11-09 16:45:12.843 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12402019-11-09 16:45:12.878 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12412019-11-09 16:45:13.731 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
12422019-11-09 16:45:13.731 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
12432019-11-09 16:45:13.732 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
12442019-11-09 16:45:14.341 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12452019-11-09 16:45:14.342 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12462019-11-09 16:45:14.343 T:1915319856 DEBUG: Keyboard: scancode: 0x3f, sym: 0x011e, unicode: 0x0000, modifier: 0x0
12472019-11-09 16:45:14.343 T:1915319856 DEBUG: HandleKey: f5 (0xf094) pressed, action is
12482019-11-09 16:45:14.521 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12492019-11-09 16:45:14.552 T:1915319856 DEBUG: Keyboard: scancode: 0x3f, sym: 0x011e, unicode: 0x0000, modifier: 0x0
12502019-11-09 16:45:16.777 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12512019-11-09 16:45:16.778 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12522019-11-09 16:45:16.799 T:1915319856 DEBUG: Keyboard: scancode: 0x2e, sym: 0x0063, unicode: 0x0063, modifier: 0x0
12532019-11-09 16:45:16.877 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12542019-11-09 16:45:16.909 T:1915319856 DEBUG: Keyboard: scancode: 0x2e, sym: 0x0063, unicode: 0x0063, modifier: 0x0
12552019-11-09 16:45:16.909 T:1915319856 DEBUG: HandleKey: c (0xf043) pressed, action is ContextMenu
12562019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers(/home/osmc/.kodi/temp/kodi.log)
12572019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12582019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12592019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
12602019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12612019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
12622019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12632019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12642019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12652019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12662019-11-09 16:45:16.910 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12672019-11-09 16:45:16.911 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12682019-11-09 16:45:16.911 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
12692019-11-09 16:45:16.911 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12702019-11-09 16:45:16.913 T:1915319856 DEBUG: CAddonDatabase: SELECT repo.id FROM repo .. took 2 ms
12712019-11-09 16:45:16.970 T:1915319856 DEBUG: CAddonDatabase: query SELECT * FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon WHERE addonlinkrepo.idRepo IN (2,3) ORDER BY addons.addonID returned 1123 rows in 57 ms
12722019-11-09 16:45:17.153 T:1915319856 DEBUG: CAddonDatabase::GetAddons took 242 ms
12732019-11-09 16:45:17.174 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
12742019-11-09 16:45:17.174 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
12752019-11-09 16:45:17.174 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12762019-11-09 16:45:17.175 T:1915319856 DEBUG: CAddonDatabase: SELECT repo.id FROM repo .. took 1 ms
12772019-11-09 16:45:17.203 T:1915319856 DEBUG: CAddonDatabase: query SELECT * FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon WHERE addonlinkrepo.idRepo IN (2,3) ORDER BY addons.addonID returned 1123 rows in 28 ms
12782019-11-09 16:45:17.386 T:1915319856 DEBUG: CAddonDatabase::GetAddons took 212 ms
12792019-11-09 16:45:17.407 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
12802019-11-09 16:45:17.407 T:1915319856 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
12812019-11-09 16:45:18.733 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
12822019-11-09 16:45:18.734 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
12832019-11-09 16:45:18.735 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
12842019-11-09 16:45:18.859 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12852019-11-09 16:45:18.859 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12862019-11-09 16:45:18.860 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
12872019-11-09 16:45:18.860 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12882019-11-09 16:45:18.959 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12892019-11-09 16:45:18.960 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12902019-11-09 16:45:19.081 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12912019-11-09 16:45:19.082 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12922019-11-09 16:45:19.093 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12932019-11-09 16:45:19.093 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
12942019-11-09 16:45:19.181 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
12952019-11-09 16:45:19.193 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
12962019-11-09 16:45:20.007 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
12972019-11-09 16:45:20.008 T:1397744352 DEBUG: Thread Timer start, auto delete: false
12982019-11-09 16:45:20.018 T:1915319856 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x001b, modifier: 0x0
12992019-11-09 16:45:20.128 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
13002019-11-09 16:45:20.152 T:1915319856 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x001b, modifier: 0x0
13012019-11-09 16:45:20.153 T:1915319856 DEBUG: HandleKey: escape (0xf01b) pressed, action is PreviousMenu
13022019-11-09 16:45:20.289 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13032019-11-09 16:45:20.290 T:1481614048 DEBUG: Thread Timer start, auto delete: false
13042019-11-09 16:45:20.309 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
13052019-11-09 16:45:20.309 T:1915319856 DEBUG: HandleKey: left (0xf082) pressed, action is Left
13062019-11-09 16:45:20.310 T:1915319856 WARNING: CGUIWindowManager - HandleAction - ignoring action 1, because topmost modal dialog closing animation is running
13072019-11-09 16:45:20.389 T:1481614048 DEBUG: Thread Timer 1481614048 terminating
13082019-11-09 16:45:20.409 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
13092019-11-09 16:45:20.416 T:1915319856 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
13102019-11-09 16:45:20.543 T:1915319856 INFO: CheckIdle - Closing session to https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct (easy=0x6ab37d20, multi=(nil))
13112019-11-09 16:45:20.709 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13122019-11-09 16:45:20.709 T:1481614048 DEBUG: Thread Timer start, auto delete: false
13132019-11-09 16:45:20.729 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
13142019-11-09 16:45:20.729 T:1915319856 DEBUG: HandleKey: left (0xf082) pressed, action is Left
13152019-11-09 16:45:20.829 T:1481614048 DEBUG: Thread Timer 1481614048 terminating
13162019-11-09 16:45:20.852 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
13172019-11-09 16:45:21.063 T:1915319856 INFO: CheckIdle - Closing session to https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct (easy=0x5c960c38, multi=0x5c9f82a0)
13182019-11-09 16:45:21.129 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13192019-11-09 16:45:21.130 T:1481614048 DEBUG: Thread Timer start, auto delete: false
13202019-11-09 16:45:21.159 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
13212019-11-09 16:45:21.269 T:1481614048 DEBUG: Thread Timer 1481614048 terminating
13222019-11-09 16:45:21.295 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
13232019-11-09 16:45:21.296 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
13242019-11-09 16:45:21.296 T:1481614048 DEBUG: Thread waiting start, auto delete: false
13252019-11-09 16:45:21.299 T:1481614048 DEBUG: Thread waiting 1481614048 terminating
13262019-11-09 16:45:21.927 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13272019-11-09 16:45:21.928 T:1481614048 DEBUG: Thread Timer start, auto delete: false
13282019-11-09 16:45:21.942 T:1915319856 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
13292019-11-09 16:45:21.942 T:1915319856 DEBUG: HandleKey: right (0xf083) pressed, action is Right
13302019-11-09 16:45:22.047 T:1481614048 DEBUG: Thread Timer 1481614048 terminating
13312019-11-09 16:45:22.069 T:1915319856 INFO: CheckIdle - Closing session to https://192-168-15-5.661ff27e710444c7a17ab371620096ed.plex.direct (easy=0x6d59dc18, multi=0x6d59b428)
13322019-11-09 16:45:22.102 T:1915319856 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
13332019-11-09 16:45:22.877 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13342019-11-09 16:45:22.877 T:1397744352 DEBUG: Thread Timer start, auto delete: false
13352019-11-09 16:45:22.906 T:1915319856 DEBUG: Keyboard: scancode: 0x2e, sym: 0x0063, unicode: 0x0063, modifier: 0x0
13362019-11-09 16:45:22.959 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
13372019-11-09 16:45:22.959 T:1915319856 DEBUG: Keyboard: scancode: 0x2e, sym: 0x0063, unicode: 0x0063, modifier: 0x0
13382019-11-09 16:45:22.960 T:1915319856 DEBUG: HandleKey: c (0xf043) pressed, action is ContextMenu
13392019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers(/home/osmc/.kodi/temp/kodi.log)
13402019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
13412019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
13422019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
13432019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
13442019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
13452019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
13462019-11-09 16:45:22.960 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
13472019-11-09 16:45:22.961 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
13482019-11-09 16:45:22.961 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
13492019-11-09 16:45:22.961 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
13502019-11-09 16:45:22.961 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
13512019-11-09 16:45:22.961 T:1915319856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
13522019-11-09 16:45:22.961 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
13532019-11-09 16:45:22.962 T:1915319856 DEBUG: CAddonDatabase: SELECT repo.id FROM repo .. took 1 ms
13542019-11-09 16:45:23.020 T:1915319856 DEBUG: CAddonDatabase: query SELECT * FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon WHERE addonlinkrepo.idRepo IN (2,3) ORDER BY addons.addonID returned 1123 rows in 58 ms
13552019-11-09 16:45:23.210 T:1915319856 DEBUG: CAddonDatabase::GetAddons took 249 ms
13562019-11-09 16:45:23.231 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
13572019-11-09 16:45:23.231 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
13582019-11-09 16:45:23.231 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
13592019-11-09 16:45:23.232 T:1915319856 DEBUG: CAddonDatabase: SELECT repo.id FROM repo .. took 1 ms
13602019-11-09 16:45:23.262 T:1915319856 DEBUG: CAddonDatabase: query SELECT * FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon WHERE addonlinkrepo.idRepo IN (2,3) ORDER BY addons.addonID returned 1123 rows in 30 ms
13612019-11-09 16:45:23.451 T:1915319856 DEBUG: CAddonDatabase::GetAddons took 221 ms
13622019-11-09 16:45:23.473 T:1915319856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
13632019-11-09 16:45:23.473 T:1915319856 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
13642019-11-09 16:45:23.741 T:1490006752 DEBUG: PLEX.plexgdm: Recieved UDP packet from [('192.168.15.5', 44857)] containing [M-SEARCH * HTTP/1.1]
13652019-11-09 16:45:23.741 T:1490006752 DEBUG: PLEX.plexgdm: Detected client discovery request from ('192.168.15.5', 44857). Replying
13662019-11-09 16:45:23.741 T:1490006752 DEBUG: PLEX.plexgdm: Sending registration data HTTP/1.0 200 OK
13672019-11-09 16:45:24.157 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13682019-11-09 16:45:24.157 T:1397744352 DEBUG: Thread Timer start, auto delete: false
13692019-11-09 16:45:24.176 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13702019-11-09 16:45:24.176 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
13712019-11-09 16:45:24.237 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
13722019-11-09 16:45:24.259 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13732019-11-09 16:45:24.343 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13742019-11-09 16:45:24.343 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13752019-11-09 16:45:24.343 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
13762019-11-09 16:45:24.343 T:1397744352 DEBUG: Thread Timer start, auto delete: false
13772019-11-09 16:45:24.423 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
13782019-11-09 16:45:24.426 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13792019-11-09 16:45:24.515 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13802019-11-09 16:45:24.516 T:1397744352 DEBUG: Thread Timer start, auto delete: false
13812019-11-09 16:45:24.531 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13822019-11-09 16:45:24.531 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
13832019-11-09 16:45:24.615 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
13842019-11-09 16:45:24.643 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13852019-11-09 16:45:24.685 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13862019-11-09 16:45:24.686 T:1397744352 DEBUG: Thread Timer start, auto delete: false
13872019-11-09 16:45:24.686 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13882019-11-09 16:45:24.686 T:1915319856 DEBUG: HandleKey: down (0xf081) pressed, action is Down
13892019-11-09 16:45:24.805 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
13902019-11-09 16:45:24.819 T:1915319856 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
13912019-11-09 16:45:25.093 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
13922019-11-09 16:45:25.094 T:1397744352 DEBUG: Thread Timer start, auto delete: false
13932019-11-09 16:45:25.109 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
13942019-11-09 16:45:25.195 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
13952019-11-09 16:45:25.221 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
13962019-11-09 16:45:25.222 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
13972019-11-09 16:45:25.467 T:1915319856 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
13982019-11-09 16:45:25.493 T:1915319856 DEBUG: ------ Window Init (DialogConfirm.xml) ------
13992019-11-09 16:45:26.793 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
14002019-11-09 16:45:26.794 T:1397744352 DEBUG: Thread Timer start, auto delete: false
14012019-11-09 16:45:26.808 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
14022019-11-09 16:45:26.808 T:1915319856 DEBUG: HandleKey: left (0xf082) pressed, action is Left
14032019-11-09 16:45:26.838 T:1766748896 DEBUG: Thread JobWorker 1766748896 terminating (autodelete)
14042019-11-09 16:45:26.893 T:1397744352 DEBUG: Thread Timer 1397744352 terminating
14052019-11-09 16:45:26.909 T:1915319856 DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
14062019-11-09 16:45:27.222 T:1351590624 DEBUG: Thread JobWorker 1351590624 terminating (autodelete)
14072019-11-09 16:45:27.241 T:1332511456 DEBUG: Thread JobWorker 1332511456 terminating (autodelete)
14082019-11-09 16:45:27.339 T:1894773472 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
14092019-11-09 16:45:27.340 T:1332511456 DEBUG: Thread Timer start, auto delete: false
14102019-11-09 16:45:27.342 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
14112019-11-09 16:45:27.391 T:1368376032 DEBUG: Thread JobWorker 1368376032 terminating (autodelete)
14122019-11-09 16:45:27.441 T:1332511456 DEBUG: Thread Timer 1332511456 terminating
14132019-11-09 16:45:27.464 T:1915319856 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
14142019-11-09 16:45:27.465 T:1915319856 DEBUG: HandleKey: return (0xf00d) pressed, action is Select
14152019-11-09 16:45:27.710 T:1915319856 DEBUG: ------ Window Deinit (DialogConfirm.xml) ------
14162019-11-09 16:45:27.726 T:1332511456 DEBUG: Thread JobWorker start, auto delete: true
14172019-11-09 16:45:27.759 T:1915319856 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------