2020-05-26 17:19:35.190 T:1915651632 DEBUG: ------ Window Init (DialogAddonSettings.xml) ------ 2020-05-26 17:19:35.190 T:1877988064 INFO: CActiveAESink::OpenSink - initialize sink 2020-05-26 17:19:35.190 T:1877988064 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 2020-05-26 17:19:35.190 T:1877988064 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI 2020-05-26 17:19:35.191 T:1877988064 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 2020-05-26 17:19:35.193 T:1877988064 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x6a97fff0 2020-05-26 17:19:35.196 T:1877988064 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16) 2020-05-26 17:19:35.197 T:1877988064 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 2020-05-26 17:19:35.197 T:1877988064 DEBUG: Output Device : HDMI 2020-05-26 17:19:35.197 T:1877988064 DEBUG: Sample Rate : 44100 2020-05-26 17:19:35.197 T:1877988064 DEBUG: Sample Format : AE_FMT_FLOAT 2020-05-26 17:19:35.197 T:1877988064 DEBUG: Channel Count : 2 2020-05-26 17:19:35.197 T:1877988064 DEBUG: Channel Layout: FL, FR 2020-05-26 17:19:35.197 T:1877988064 DEBUG: Frames : 2205 2020-05-26 17:19:35.197 T:1877988064 DEBUG: Frame Size : 8 2020-05-26 17:19:35.384 T:1577050848 DEBUG: PushCecKeypress - received key b duration 216 (rep:0 size:0) 2020-05-26 17:19:35.384 T:1577050848 DEBUG: PushCecKeypress - ignored key b 2020-05-26 17:19:36.188 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:36.188 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:36.217 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:36.598 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:36.598 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:36.606 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:36.788 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:36.788 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:36.800 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:36.978 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:36.978 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:37.002 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:37.198 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:37.198 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:37.240 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:37.464 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:37.464 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:37.473 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:37.590 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 1402 (rep:0 size:0) 2020-05-26 17:19:37.590 T:1577050848 DEBUG: PushCecKeypress - ignored key a7 2020-05-26 17:19:37.595 T:1351738080 DEBUG: [service.sleepy.watchdog] user activity detected, reset idle time 2020-05-26 17:19:37.923 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:37.923 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:37.956 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:38.184 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 261 (rep:0 size:0) 2020-05-26 17:19:38.184 T:1577050848 DEBUG: PushCecKeypress - ignored key a7 2020-05-26 17:19:38.548 T:1577050848 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 2020-05-26 17:19:38.548 T:1577050848 DEBUG: PushCecKeypress - added key b 2020-05-26 17:19:38.590 T:1915651632 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select 2020-05-26 17:19:38.764 T:1577050848 DEBUG: PushCecKeypress - received key b duration 216 (rep:0 size:0) 2020-05-26 17:19:38.764 T:1577050848 DEBUG: PushCecKeypress - ignored key b 2020-05-26 17:19:39.168 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 2020-05-26 17:19:39.168 T:1577050848 DEBUG: PushCecKeypress - added key a7 2020-05-26 17:19:39.200 T:1915651632 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2020-05-26 17:19:39.440 T:1577050848 DEBUG: PushCecKeypress - received key a7 duration 272 (rep:0 size:0) 2020-05-26 17:19:39.441 T:1577050848 DEBUG: PushCecKeypress - ignored key a7 2020-05-26 17:19:41.258 T:1577050848 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 2020-05-26 17:19:41.258 T:1577050848 DEBUG: PushCecKeypress - added key b 2020-05-26 17:19:41.267 T:1915651632 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select 2020-05-26 17:19:41.497 T:1915651632 DEBUG: ------ Window Deinit (DialogAddonSettings.xml) ------ 2020-05-26 17:19:41.514 T:1577050848 DEBUG: PushCecKeypress - received key b duration 256 (rep:0 size:0) 2020-05-26 17:19:41.514 T:1577050848 DEBUG: PushCecKeypress - ignored key b 2020-05-26 17:19:41.544 T:1915651632 DEBUG: CAddonSettings[service.sleepy.watchdog]: loading setting definitions 2020-05-26 17:19:41.544 T:1915651632 DEBUG: CAddonSettings[service.sleepy.watchdog]: trying to load setting definitions from old format... 2020-05-26 17:19:41.547 T:1915651632 DEBUG: CAddonSettings[service.sleepy.watchdog]: loading setting values 2020-05-26 17:19:42.595 T:1351738080 DEBUG: [service.sleepy.watchdog] settings changed 2020-05-26 17:19:42.596 T:1351738080 DEBUG: [service.sleepy.watchdog] settings (re)loaded... 2020-05-26 17:19:42.596 T:1351738080 DEBUG: [service.sleepy.watchdog] notify user: True 2020-05-26 17:19:42.596 T:1351738080 DEBUG: [service.sleepy.watchdog] Duration of notification: 15 2020-05-26 17:19:42.596 T:1351738080 DEBUG: [service.sleepy.watchdog] send CEC: True 2020-05-26 17:19:42.596 T:1351738080 DEBUG: [service.sleepy.watchdog] Time frame: False 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] Activity start: 75600 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] Activity stop: 108000 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] max. idle time: 900 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] Action: 32132 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] Jump to main menue: True 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] Keep alive: True 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] Run addon: 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] Test configuration: True 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] running in test mode for 75 secs 2020-05-26 17:19:42.597 T:1351738080 DEBUG: [service.sleepy.watchdog] user activity detected, reset idle time 2020-05-26 17:20:41.651 T:1877988064 DEBUG: CAESinkPi:Drain delay:99ms now:0ms 2020-05-26 17:20:47.599 T:1351738080 DEBUG: [service.sleepy.watchdog] max idle time reached, ready to perform some action 2020-05-26 17:20:47.600 T:1351738080 DEBUG: [service.sleepy.watchdog] init notification countdown for action no. 32132 2020-05-26 17:20:47.606 T:1915651632 DEBUG: ------ Window Init (DialogNotification.xml) ------ 2020-05-26 17:20:47.607 T:1877988064 NOTICE: CAESinkPi:AddPackets Underrun (delay:0.00 frames:2205) 2020-05-26 17:20:51.028 T:1915651632 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 2020-05-26 17:20:57.647 T:1915651632 DEBUG: ------ Window Init (DialogNotification.xml) ------ 2020-05-26 17:21:00.994 T:1915651632 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 2020-05-26 17:21:07.604 T:1351738080 DEBUG: [service.sleepy.watchdog] send standby command via CEC 2020-05-26 17:21:07.738 T:1351738080 DEBUG: [service.sleepy.watchdog] 2020-05-26 17:21:07.738 T:1351738080 DEBUG: [service.sleepy.watchdog] None 2020-05-26 17:21:07.739 T:1351738080 DEBUG: [service.sleepy.watchdog] init system shutdown 2020-05-26 17:21:07.739 T:1351738080 DEBUG: [service.sleepy.watchdog] watchdog was running in test mode, keep it alive 2020-05-26 17:21:07.742 T:1351738080 DEBUG: CAddonSettings[service.sleepy.watchdog]: loading setting definitions 2020-05-26 17:21:07.742 T:1351738080 DEBUG: CAddonSettings[service.sleepy.watchdog]: trying to load setting definitions from old format... 2020-05-26 17:21:07.743 T:1351738080 DEBUG: CAddonSettings[service.sleepy.watchdog]: loading setting values 2020-05-26 17:21:07.767 T:1915651632 DEBUG: LogindUPowerSyscall - inhibit lock taken, fd 32 2020-05-26 17:21:07.776 T:1915651632 DEBUG: ------ Window Init (DialogBusy.xml) ------ 2020-05-26 17:21:07.776 T:1915651632 NOTICE: Stopping player 2020-05-26 17:21:07.776 T:1915651632 NOTICE: Stopping all 2020-05-26 17:21:07.776 T:1915651632 DEBUG: NetworkMessage - Signaling network services to stop 2020-05-26 17:21:07.776 T:1915651632 NOTICE: ES: Stopping event server 2020-05-26 17:21:07.835 T:1673519840 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: System.OnQuit - data: {"exitcode":64} 2020-05-26 17:21:07.844 T:1351738080 DEBUG: [service.sleepy.watchdog] Notification triggered 2020-05-26 17:21:07.844 T:1351738080 DEBUG: [service.sleepy.watchdog] sender: xbmc 2020-05-26 17:21:07.844 T:1351738080 DEBUG: [service.sleepy.watchdog] method: System.OnQuit 2020-05-26 17:21:07.844 T:1351738080 DEBUG: [service.sleepy.watchdog] data: {"exitcode":64} 2020-05-26 17:21:07.903 T:1597985504 DEBUG: Thread TCPServer 1597985504 terminating 2020-05-26 17:21:08.007 T:1606378208 NOTICE: ES: UDP Event server stopped 2020-05-26 17:21:08.007 T:1606378208 DEBUG: Thread EventServer 1606378208 terminating 2020-05-26 17:21:08.280 T:1541403360 DEBUG: Thread CECAdapterUpdate 1541403360 terminating 2020-05-26 17:21:08.432 T:1915651632 DEBUG: CZeroconfAvahi::doRemoveService named: servers.airtunes 2020-05-26 17:21:08.434 T:1915651632 DEBUG: NetworkMessage - Waiting for network services to stop 2020-05-26 17:21:08.434 T:1915651632 NOTICE: stopping zeroconf publishing 2020-05-26 17:21:08.440 T:1915651632 NOTICE: CWebServer[80]: Stopped 2020-05-26 17:21:08.440 T:1915651632 NOTICE: stop dvd detect media 2020-05-26 17:21:08.454 T:1915651632 DEBUG: CServiceAddonManager: stopping script.lazytv. 2020-05-26 17:21:08.455 T:1915651632 INFO: CServiceAddonManager: failed to stop script.lazytv (may have ended) 2020-05-26 17:21:08.455 T:1915651632 DEBUG: CServiceAddonManager: stopping script.module.osmcsetting.updates. 2020-05-26 17:21:08.455 T:1915651632 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/script.module.osmcsetting.updates/service.py): trigger Monitor abort request 2020-05-26 17:21:08.511 T:1700766432 DEBUG: script.module.osmcsetting.updates : comm_from_script - [u'kill_yourself', {}] 2020-05-26 17:21:08.512 T:1700766432 DEBUG: OSMC UPDATE COMMS: Connection stop called 2020-05-26 17:21:08.512 T:1700766432 DEBUG: OSMC UPDATE COMMS: Connection stopping. 2020-05-26 17:21:08.512 T:1700766432 DEBUG: OSMC UPDATE COMMS: Exit message sent to socket. 2020-05-26 17:21:08.513 T:1700766432 INFO: CPythonInvoker(0, /usr/share/kodi/addons/script.module.osmcsetting.updates/service.py): script successfully run 2020-05-26 17:21:08.513 T:1700766432 INFO: CPythonInvoker(0, /usr/share/kodi/addons/script.module.osmcsetting.updates/service.py): waiting on thread 1492120288 2020-05-26 17:21:08.513 T:1492120288 DEBUG: OSMC UPDATE COMMS: Connection active. 2020-05-26 17:21:08.513 T:1492120288 DEBUG: OSMC UPDATE COMMS: data = exit 2020-05-26 17:21:08.514 T:1492120288 DEBUG: Previous line repeats 1 times. 2020-05-26 17:21:08.514 T:1492120288 DEBUG: OSMC UPDATE COMMS: Connection called to "exit" 2020-05-26 17:21:08.514 T:1492120288 DEBUG: OSMC UPDATE COMMS: Comms Ended 2020-05-26 17:21:08.613 T:1700766432 DEBUG: onExecutionDone(0, /usr/share/kodi/addons/script.module.osmcsetting.updates/service.py) 2020-05-26 17:21:08.619 T:1568658144 DEBUG: Process - CEC adapter processor thread ended 2020-05-26 17:21:08.619 T:1568658144 DEBUG: Thread CECAdapter 1568658144 terminating 2020-05-26 17:21:08.638 T:1700766432 WARNING: CPythonInvoker(0, /usr/share/kodi/addons/script.module.osmcsetting.updates/service.py): the python script "/usr/share/kodi/addons/script.module.osmcsetting.updates/service.py" has left several classes in memory that we couldn't clean up. The classes include: N9XBMCAddon7xbmcgui12ControlImageE,N14PythonBindings31XBMCAddon_xbmc_Monitor_DirectorE,N9XBMCAddon7xbmcgui6WindowE 2020-05-26 17:21:08.638 T:1700766432 INFO: Python interpreter interrupted by user 2020-05-26 17:21:08.639 T:1915651632 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/script.module.osmcsetting.updates/service.py): script termination took 184ms 2020-05-26 17:21:08.639 T:1915651632 DEBUG: CServiceAddonManager: stopping script.skin.helper.service. 2020-05-26 17:21:08.639 T:1700766432 DEBUG: Thread LanguageInvoker 1700766432 terminating 2020-05-26 17:21:08.639 T:1915651632 DEBUG: CPythonInvoker(3, /home/osmc/.kodi/addons/script.skin.helper.service/service.py): trigger Monitor abort request 2020-05-26 17:21:08.640 T:1673519840 NOTICE: Skin Helper Service --> Shutdown requested ! 2020-05-26 17:21:08.640 T:1673519840 DEBUG: Skin Helper Service --> ListItemMonitor - stop called 2020-05-26 17:21:08.640 T:1673519840 DEBUG: Skin Helper Service --> WebService - stop called 2020-05-26 17:21:08.644 T:1475334880 DEBUG: Skin Helper Service --> Webservice --> [26/May/2020 17:21:08] "QUIT / HTTP/1.1" 200 - 2020-05-26 17:21:08.650 T:1673519840 DEBUG: Skin Helper Simplecache --> Closed 2020-05-26 17:21:08.651 T:1673519840 DEBUG: Previous line repeats 4 times. 2020-05-26 17:21:08.651 T:1673519840 DEBUG: Metadata and Artwork module --> Exited 2020-05-26 17:21:08.651 T:1673519840 NOTICE: Skin Helper Service --> Skin Helper Service version 1.1.3 stopped 2020-05-26 17:21:08.652 T:1673519840 DEBUG: Skin Helper Simplecache --> Closed 2020-05-26 17:21:08.653 T:1673519840 INFO: CPythonInvoker(3, /home/osmc/.kodi/addons/script.skin.helper.service/service.py): script successfully run 2020-05-26 17:21:08.653 T:1673519840 DEBUG: onExecutionDone(3, /home/osmc/.kodi/addons/script.skin.helper.service/service.py) 2020-05-26 17:21:08.706 T:1673519840 INFO: Python interpreter interrupted by user 2020-05-26 17:21:08.706 T:1915651632 DEBUG: CPythonInvoker(3, /home/osmc/.kodi/addons/script.skin.helper.service/service.py): script termination took 67ms 2020-05-26 17:21:08.706 T:1915651632 DEBUG: CServiceAddonManager: stopping script.tv.show.next.aired. 2020-05-26 17:21:08.706 T:1673519840 DEBUG: Thread LanguageInvoker 1673519840 terminating 2020-05-26 17:21:08.706 T:1915651632 DEBUG: CPythonInvoker(4, /home/osmc/.kodi/addons/script.tv.show.next.aired/service.py): trigger Monitor abort request 2020-05-26 17:21:08.709 T:1665127136 NOTICE: script.tv.show.next.aired: ### abort requested -- stopping background processing 2020-05-26 17:21:08.709 T:1665127136 INFO: CPythonInvoker(4, /home/osmc/.kodi/addons/script.tv.show.next.aired/service.py): script aborted 2020-05-26 17:21:08.709 T:1665127136 DEBUG: onExecutionDone(4, /home/osmc/.kodi/addons/script.tv.show.next.aired/service.py) 2020-05-26 17:21:08.721 T:1665127136 INFO: Python interpreter interrupted by user 2020-05-26 17:21:08.721 T:1915651632 DEBUG: CPythonInvoker(4, /home/osmc/.kodi/addons/script.tv.show.next.aired/service.py): script termination took 15ms 2020-05-26 17:21:08.722 T:1915651632 DEBUG: CServiceAddonManager: stopping service.library.data.provider. 2020-05-26 17:21:08.722 T:1915651632 DEBUG: CPythonInvoker(5, /home/osmc/.kodi/addons/service.library.data.provider/service.py): trigger Monitor abort request 2020-05-26 17:21:08.722 T:1656734432 DEBUG: Library Data Provider: service version 0.3.2 stopped 2020-05-26 17:21:08.722 T:1656734432 INFO: CPythonInvoker(5, /home/osmc/.kodi/addons/service.library.data.provider/service.py): script successfully run 2020-05-26 17:21:08.722 T:1656734432 DEBUG: onExecutionDone(5, /home/osmc/.kodi/addons/service.library.data.provider/service.py) 2020-05-26 17:21:08.727 T:1656734432 INFO: Python interpreter interrupted by user 2020-05-26 17:21:08.727 T:1915651632 DEBUG: CPythonInvoker(5, /home/osmc/.kodi/addons/service.library.data.provider/service.py): script termination took 5ms 2020-05-26 17:21:08.727 T:1915651632 DEBUG: CServiceAddonManager: stopping service.osmc.settings. 2020-05-26 17:21:08.727 T:1915651632 DEBUG: CPythonInvoker(1, /usr/share/kodi/addons/service.osmc.settings/service.py): trigger Monitor abort request 2020-05-26 17:21:08.727 T:1692373728 DEBUG: OSMC ADDON MAIN Stopping listener (in wait) 2020-05-26 17:21:08.727 T:1692373728 DEBUG: OSMC COMMS: Connection stop called 2020-05-26 17:21:08.727 T:1692373728 DEBUG: OSMC COMMS: Connection stopping. 2020-05-26 17:21:08.728 T:1692373728 DEBUG: OSMC COMMS: Exit message sent to socket. 2020-05-26 17:21:08.728 T:1692373728 DEBUG: OSMC ADDON MAIN Deleting listener (in wait) 2020-05-26 17:21:08.728 T:1692373728 DEBUG: OSMC ADDON MAIN Listener deleted. 2020-05-26 17:21:08.728 T:1692373728 DEBUG: OSMC ADDON MAIN _daemon exiting 2020-05-26 17:21:08.728 T:1692373728 DEBUG: OSMC ADDON MAIN _daemon exited 2020-05-26 17:21:08.728 T:1692373728 DEBUG: OSMC ADDON MAIN Exiting OSMC Settings 2020-05-26 17:21:08.728 T:1692373728 INFO: CPythonInvoker(1, /usr/share/kodi/addons/service.osmc.settings/service.py): script successfully run 2020-05-26 17:21:08.728 T:1692373728 INFO: CPythonInvoker(1, /usr/share/kodi/addons/service.osmc.settings/service.py): waiting on thread 1519903456 2020-05-26 17:21:08.728 T:1519903456 DEBUG: OSMC COMMS: Connection active. 2020-05-26 17:21:08.729 T:1519903456 DEBUG: OSMC COMMS: data = exit 2020-05-26 17:21:08.729 T:1519903456 DEBUG: Previous line repeats 1 times. 2020-05-26 17:21:08.729 T:1519903456 DEBUG: OSMC COMMS: Connection called to "exit" 2020-05-26 17:21:08.729 T:1519903456 DEBUG: OSMC COMMS: Comms Ended 2020-05-26 17:21:08.828 T:1692373728 DEBUG: onExecutionDone(1, /usr/share/kodi/addons/service.osmc.settings/service.py) 2020-05-26 17:21:08.852 T:1692373728 INFO: Python interpreter interrupted by user 2020-05-26 17:21:08.852 T:1915651632 DEBUG: CPythonInvoker(1, /usr/share/kodi/addons/service.osmc.settings/service.py): script termination took 125ms 2020-05-26 17:21:08.852 T:1915651632 DEBUG: CServiceAddonManager: stopping service.sleepy.watchdog. 2020-05-26 17:21:08.852 T:1915651632 DEBUG: CPythonInvoker(8, /home/osmc/.kodi/addons/service.sleepy.watchdog/default.py): trigger Monitor abort request 2020-05-26 17:21:08.852 T:1351738080 DEBUG: [service.sleepy.watchdog] Sleepy Watchdog kicks off 2020-05-26 17:21:08.853 T:1351738080 INFO: CPythonInvoker(8, /home/osmc/.kodi/addons/service.sleepy.watchdog/default.py): script successfully run 2020-05-26 17:21:08.853 T:1351738080 DEBUG: onExecutionDone(8, /home/osmc/.kodi/addons/service.sleepy.watchdog/default.py) 2020-05-26 17:21:08.859 T:1351738080 INFO: Python interpreter interrupted by user 2020-05-26 17:21:08.859 T:1915651632 DEBUG: CPythonInvoker(8, /home/osmc/.kodi/addons/service.sleepy.watchdog/default.py): script termination took 7ms 2020-05-26 17:21:08.859 T:1915651632 DEBUG: CServiceAddonManager: stopping service.upnext. 2020-05-26 17:21:08.859 T:1665127136 DEBUG: Thread LanguageInvoker 1665127136 terminating 2020-05-26 17:21:08.859 T:1915651632 DEBUG: CPythonInvoker(2, /home/osmc/.kodi/addons/service.upnext/resources/lib/service_entry.py): trigger Monitor abort request 2020-05-26 17:21:08.861 T:1681912544 DEBUG: CAddonSettings[service.upnext]: loading setting definitions 2020-05-26 17:21:08.861 T:1681912544 DEBUG: CAddonSettings[service.upnext]: trying to load setting definitions from old format... 2020-05-26 17:21:08.862 T:1681912544 DEBUG: CAddonSettings[service.upnext]: loading setting values 2020-05-26 17:21:08.862 T:1681912544 DEBUG: CSettingsManager: requested setting (developerMode) was not found. 2020-05-26 17:21:08.862 T:1681912544 DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting developerMode. Creating a setting on-the-fly... 2020-05-26 17:21:08.862 T:1681912544 DEBUG: CSettingsManager: requested setting (shortPlayLength) was not found. 2020-05-26 17:21:08.862 T:1681912544 DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayLength. Creating a setting on-the-fly... 2020-05-26 17:21:08.862 T:1681912544 DEBUG: CSettingsManager: requested setting (shortPlayMode) was not found. 2020-05-26 17:21:08.862 T:1681912544 DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayMode. Creating a setting on-the-fly... 2020-05-26 17:21:08.863 T:1681912544 DEBUG: CSettingsManager: requested setting (shortPlayNotification) was not found. 2020-05-26 17:21:08.863 T:1681912544 DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayNotification. Creating a setting on-the-fly... 2020-05-26 17:21:08.863 T:1681912544 DEBUG: CSettingsManager: requested setting (windowMode) was not found. 2020-05-26 17:21:08.863 T:1681912544 DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting windowMode. Creating a setting on-the-fly... 2020-05-26 17:21:08.864 T:1681912544 DEBUG: [service.upnext] UpNextMonitor -> Service stopped 2020-05-26 17:21:08.864 T:1681912544 INFO: CPythonInvoker(2, /home/osmc/.kodi/addons/service.upnext/resources/lib/service_entry.py): script successfully run 2020-05-26 17:21:08.864 T:1681912544 DEBUG: onExecutionDone(2, /home/osmc/.kodi/addons/service.upnext/resources/lib/service_entry.py) 2020-05-26 17:21:08.870 T:1681912544 INFO: Python interpreter interrupted by user 2020-05-26 17:21:08.870 T:1915651632 DEBUG: CPythonInvoker(2, /home/osmc/.kodi/addons/service.upnext/resources/lib/service_entry.py): script termination took 4ms 2020-05-26 17:21:08.870 T:1692373728 DEBUG: Thread LanguageInvoker 1692373728 terminating 2020-05-26 17:21:08.870 T:1886380768 DEBUG: Thread ActiveAE 1886380768 terminating 2020-05-26 17:21:08.870 T:1351738080 DEBUG: Thread LanguageInvoker 1351738080 terminating 2020-05-26 17:21:08.870 T:1681912544 DEBUG: Thread LanguageInvoker 1681912544 terminating 2020-05-26 17:21:08.870 T:1656734432 DEBUG: Thread LanguageInvoker 1656734432 terminating 2020-05-26 17:21:08.907 T:1877988064 DEBUG: Thread AESink 1877988064 terminating 2020-05-26 17:21:09.008 T:1915651632 DEBUG: CAESinkPi:Drain delay:100ms now:0ms 2020-05-26 17:21:09.008 T:1915651632 DEBUG: CAESinkPi:Deinitialize 2020-05-26 17:21:09.008 T:1915651632 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000 2020-05-26 17:21:09.011 T:1915651632 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x6a97fff0 2020-05-26 17:21:09.012 T:1915651632 NOTICE: Application stopped 2020-05-26 17:21:09.212 T:1915651632 NOTICE: XBApplicationEx: destroying... 2020-05-26 17:21:09.212 T:1717551840 DEBUG: Thread PeripEventScanner 1717551840 terminating 2020-05-26 17:21:09.222 T:1742729952 DEBUG: Thread PeripBusUSBUdev 1742729952 terminating 2020-05-26 17:21:09.622 T:1725944544 DEBUG: Thread PeripBusAddon 1725944544 terminating 2020-05-26 17:21:09.879 T:1915651632 INFO: AddOnLog: Joystick Support: Disabling joystick interface "linux" 2020-05-26 17:21:09.880 T:1915651632 DEBUG: SECTION:UnloadDll(/usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.4.9) 2020-05-26 17:21:09.880 T:1915651632 INFO: ADDON: Dll Destroyed - Joystick Support 2020-05-26 17:21:09.880 T:1915651632 NOTICE: unload skin 2020-05-26 17:21:09.880 T:1915651632 INFO: Unloading old skin ... 2020-05-26 17:21:09.890 T:1915651632 DEBUG: FreeVisualisation() done 2020-05-26 17:21:09.896 T:1915651632 DEBUG: ------ Window Deinit () ------ 2020-05-26 17:21:09.897 T:1915651632 DEBUG: FreeVisualisation() done 2020-05-26 17:21:09.930 T:1915651632 DEBUG: Previous line repeats 4 times. 2020-05-26 17:21:09.930 T:1915651632 DEBUG: ------ Window Deinit (AddonBrowser.xml) ------ 2020-05-26 17:21:09.930 T:1915651632 DEBUG: FreeVisualisation() done 2020-05-26 17:21:09.934 T:1915651632 DEBUG: Previous line repeats 1 times. 2020-05-26 17:21:09.934 T:1915651632 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 2020-05-26 17:21:09.935 T:1915651632 DEBUG: ------ Window Deinit (DialogAddonInfo.xml) ------ 2020-05-26 17:21:09.949 T:1915651632 WARNING: Cleanup: Having to cleanup texture button-nofocus.png 2020-05-26 17:21:09.949 T:1915651632 WARNING: Cleanup: Having to cleanup texture button-focus.png 2020-05-26 17:21:09.949 T:1915651632 DEBUG: CloseBundle - Closed bundle 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool '!isempty(window(home).property(skinhelper.enableanimatedposters)) + container.content(movies)' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool '!isempty(window(home).property(skinhelper.enablemusicart)) + [container.content(albums) | container.content(artists) | container.content(songs)]' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool '!isempty(window(home).property(skinhelper.enablepvrthumbs)) + [window.isactive(mypvrchannels.xml) | window.isactive(mypvrguide.xml) | window.isactive(mypvrrecordings.xml) | window.isactive(mypvrtimers.xml) | window.isactive(mypvrsearch.xml)]' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool '!skin.hassetting(disablecontext:script.playrandomvideos) + [window.isvisible(videos) | stringcompare(listitem.dbtype, set) | stringcompare(listitem.dbtype, tvshow) | stringcompare(listitem.dbtype, season) | string.isequal(listitem.dbtype, set) | string.isequal(listitem.dbtype, tvshow) | string.isequal(listitem.dbtype, season)] + listitem.isfolder + !listitem.isparentfolder + ![substring(listitem.folderpath, plugin, left) | substring(listitem.folderpath, addons, left) | substring(listitem.folderpath, sources, left) | string.startswith(listitem.folderpath, plugin) | string.startswith(listitem.folderpath, addons) | string.startswith(listitem.folderpath, sources)] + ![stringcompare(listitem.folderpath, add) | string.isequal(listitem.folderpath, add)]' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'container.content(albums)' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'container.content(artists)' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'container.content(movies)' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'container.content(songs)' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'isempty(window(home).property(skinhelper.enableanimatedposters))' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'isempty(window(home).property(skinhelper.enablemusicart))' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'isempty(window(home).property(skinhelper.enablepvrthumbs))' still used by 3 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'listitem.isfolder' still used by 4 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'listitem.isparentfolder' still used by 4 instances 2020-05-26 17:21:10.038 T:1915651632 DEBUG: Infobool 'skin.hassetting(disablecontext:script.playrandomvideos)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'skin.hassetting(enablecontext:script.playrandomvideos.single)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'skin.hassetting(enablecontext:script.playrandomvideos.single) + [window.isvisible(videos) | stringcompare(listitem.dbtype, set) | stringcompare(listitem.dbtype, tvshow) | stringcompare(listitem.dbtype, season) | string.isequal(listitem.dbtype, set) | string.isequal(listitem.dbtype, tvshow) | string.isequal(listitem.dbtype, season)] + listitem.isfolder + !listitem.isparentfolder + ![substring(listitem.folderpath, plugin, left) | substring(listitem.folderpath, addons, left) | substring(listitem.folderpath, sources, left) | string.startswith(listitem.folderpath, plugin) | string.startswith(listitem.folderpath, addons) | string.startswith(listitem.folderpath, sources)] + ![stringcompare(listitem.folderpath, add) | string.isequal(listitem.folderpath, add)]' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.isequal(listitem.dbtype, season)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.isequal(listitem.dbtype, set)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.isequal(listitem.dbtype, tvshow)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.isequal(listitem.folderpath, add)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.startswith(listitem.folderpath, addons)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.startswith(listitem.folderpath, plugin)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.startswith(listitem.folderpath, sources)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'stringcompare(listitem.dbtype, season)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'stringcompare(listitem.dbtype, set)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'stringcompare(listitem.dbtype, tvshow)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'stringcompare(listitem.folderpath, add)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'substring(listitem.folderpath, addons, left)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'substring(listitem.folderpath, plugin, left)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'substring(listitem.folderpath, sources, left)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'window.isactive(mypvrchannels.xml)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'window.isactive(mypvrguide.xml)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'window.isactive(mypvrrecordings.xml)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'window.isactive(mypvrsearch.xml)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'window.isactive(mypvrtimers.xml)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'window.isvisible(videos)' still used by 4 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool '!string.isempty(listitem.addonbroken)' still used by 3 instances 2020-05-26 17:21:10.039 T:1915651632 DEBUG: Infobool 'string.isempty(listitem.addonbroken)' still used by 3 instances 2020-05-26 17:21:10.065 T:1915651632 NOTICE: unload sections 2020-05-26 17:21:10.074 T:1915651632 DEBUG: LogindUPowerSyscall - delay lock sleep released 2020-05-26 17:21:10.074 T:1915651632 DEBUG: LogindUPowerSyscall - delay lock shutdown released 2020-05-26 17:21:10.077 T:1915651632 ERROR: AddOnLog: RAR archive support: CRARControl::CleanupPasswordList: invalid data (no/invalid data file found at '/home/osmc/.kodi/userdata/addon_data/vfs.rar//rar-control.xml') 2020-05-26 17:21:10.077 T:1915651632 DEBUG: SECTION:UnloadDll(/usr/lib/kodi/addons/vfs.rar/vfs.rar.so.2.2.2) 2020-05-26 17:21:10.077 T:1915651632 INFO: ADDON: Dll Destroyed - RAR archive support 2020-05-26 17:21:10.078 T:1528296160 DEBUG: Thread Timer 1528296160 terminating 2020-05-26 17:21:10.088 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a06610 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.088 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3aeca18 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.088 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a1d098 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a85f98 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a21820 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3acfd20 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3ae9920 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a0bc28 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3aeaf30 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3ade638 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3ade2b8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a6eac0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3aa6ec0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a2c248 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a02ec8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a0dc50 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a86b50 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a0b9d0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3aa7dd0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.089 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a2ea58 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.090 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a206d8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.090 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x39f02d8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.090 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a880d8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.098 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3aea1d8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.098 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a1f360 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.098 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a6dd60 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.098 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3acfb60 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.098 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3ab2f68 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.098 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3add9e8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.098 T:1915651632 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x3a0a270 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 0 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 1 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 2 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 3 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 4 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 5 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 6 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 7 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 8 --> 0 instances 2020-05-26 17:21:10.117 T:1915651632 DEBUG: object 9 --> 0 instances 2020-05-26 17:21:10.129 T:1631556320 INFO: CZeroconfAvahi::clientCallback: client failure. avahi-daemon stopped? Recreating client... 2020-05-26 17:21:10.156 T:1894773472 DEBUG: Thread libinput 1894773472 terminating 2020-05-26 17:21:10.157 T:1905259232 DEBUG: Thread Lirc 1905259232 terminating 2020-05-26 17:21:10.168 T:1915638496 DEBUG: Thread Announce 1915638496 terminating 2020-05-26 17:21:10.183 T:1915651632 NOTICE: XBApplicationEx: application stopped! 2020-05-26 17:21:10.183 T:1804571360 DEBUG: Thread CRBPWorker 1804571360 terminating