search

steeve / plugin.video.pulsar

Pulsar addon for XBMC/Kodi
http://forum.kodi.tv/showthread.php?tid=200957
Other
547 stars 351 forks source link

Can't run 0.4.5 on Xios #100

Open inieto opened 9 years ago

inieto commented 9 years ago

As with 0.4.2, it throws this exception:

15:39:43 T:1355805792  NOTICE: [plugin.video.pulsar] pulsard: starting pulsard
...
15:39:44 T:1355805792  NOTICE: Traceback (most recent call last):
15:39:44 T:1355805792  NOTICE:   File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 104, in pulsard_thread
15:39:44 T:1355805792  NOTICE:     proc = start_pulsard(stdout=subprocess.PIPE, stderr=subprocess.STDOUT)
15:39:44 T:1355805792  NOTICE:   File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 89, in start_pulsard
15:39:44 T:1355805792  NOTICE:     return subprocess.Popen(args, **kwargs)
15:39:44 T:1355805792  NOTICE:   File "/usr/lib/python2.7/subprocess.py", line 679, in __init__
15:39:44 T:1355805792  NOTICE:   File "/usr/lib/python2.7/subprocess.py", line 1249, in _execute_child
15:39:44 T:1355805792  NOTICE: OSError: [Errno 2] No such file or directory
15:39:44 T:1355805792   ERROR: Exception in thread Thread-2:
                                            Traceback (most recent call last):
                                              File "/usr/lib/python2.7/threading.py", line 551, in __bootstrap_inner
                                              File "/usr/lib/python2.7/threading.py", line 504, in run
                                              File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 104, in pulsard_thread
                                                proc = start_pulsard(stdout=subprocess.PIPE, stderr=subprocess.STDOUT)
                                              File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 89, in start_pulsard
                                                return subprocess.Popen(args, **kwargs)
                                              File "/usr/lib/python2.7/subprocess.py", line 679, in __init__
                                              File "/usr/lib/python2.7/subprocess.py", line 1249, in _execute_child
                                            OSError: [Errno 2] No such file or directory

/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py, line 103/4 are:

log.info("pulsard: starting pulsard")
proc = start_pulsard(stdout=subprocess.PIPE, stderr=subprocess.STDOUT)

Then I've modified daemon.py to dump some variables and had:

20:30:56 T:1353856096  NOTICE: [plugin.video.pulsar]  PLATFORM: {"os": "linux", "arch": "arm"}
20:30:56 T:1353856096  NOTICE: [plugin.video.pulsar]  args: ["/root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm/pulsar"]
20:30:56 T:1353856096  NOTICE: [plugin.video.pulsar]  **kwargs: {"cwd": "/root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm", "
stderr": -2, "stdout": -1}

This is the modified code to dump the information:

def start_pulsard(**kwargs):                                                                                                  
    # Make sure all other pulsard instances are closed                                                                        
    try:                                                                                                                      
        urllib2.urlopen(PULSARD_HOST + "/shutdown")                                                                           
    except urllib2.URLError:                                                                                                  
        pass                                                                                                                  
    pulsar_dir, pulsar_binary = get_pulsar_binary()                                                                           
    args = [pulsar_binary]                                                                                                    
    kwargs["cwd"] = pulsar_dir                                                                                                
    if PLATFORM["os"] == "windows":                                                                                           
        si = subprocess.STARTUPINFO()                                                                                         
        si.dwFlags = STARTF_USESHOWWINDOW                                                                                     
        si.wShowWindow = SW_HIDE                                                                                              
        kwargs["startupinfo"] = si                                                                                            
    log.info(" PLATFORM: " + json.dumps(PLATFORM))                                                                          
    log.info(" args: "+ json.dumps([pulsar_binary]))                                                                        
    log.info(" **kwargs: "+ json.dumps(kwargs))                                                                             
    return subprocess.Popen(args, **kwargs)

How can I know which file is missing?

Thanks!

inieto commented 9 years ago

This is the full log:

[root@pivos-m1 temp]# cat kodi.log
18:39:32 T:1099901136  NOTICE: special://profile/ is mapped to: special://masterprofile/
18:39:32 T:1099901136  NOTICE: -----------------------------------------------------------------------
18:39:32 T:1099901136  NOTICE: Starting Kodi (14.0 Git:2014-12-31). Platform: Linux ARM 32-bit
18:39:32 T:1099901136  NOTICE: Using Debug Kodi x32 build
18:39:32 T:1099901136  NOTICE: Kodi compiled Jan  2 2015 by GCC 4.4.1 for Linux ARM 32-bit version 2.6.32 (132640)
18:39:32 T:1099901136  NOTICE: Running on Buildroot 2013.11, kernel: Linux ARM 32-bit version 2.6.34
18:39:32 T:1099901136  NOTICE: FFmpeg statically linked, version: 2.4.4
18:39:32 T:1099901136  NOTICE: WARNING: unsupported ffmpeg version detected
18:39:32 T:1099901136  NOTICE: Host CPU: ARMv7 Processor rev 1 (v7l), 0 cores available
18:39:32 T:1099901136  NOTICE: ARM Features: Neon enabled
18:39:32 T:1099901136  NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
18:39:32 T:1099901136  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
18:39:32 T:1099901136  NOTICE: special://masterprofile/ is mapped to: /root/.kodi/userdata
18:39:32 T:1099901136  NOTICE: special://home/ is mapped to: /root/.kodi
18:39:32 T:1099901136  NOTICE: special://temp/ is mapped to: /root/.kodi/temp
18:39:32 T:1099901136  NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
18:39:32 T:1099901136  NOTICE: Local hostname: pivos-m1
18:39:32 T:1099901136  NOTICE: Log File is located: /root/.kodi/temp/kodi.log
18:39:32 T:1099901136  NOTICE: -----------------------------------------------------------------------
18:39:32 T:1099901136   DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.ConsoleKit was not provided by any .service files
18:39:32 T:1099901136   DEBUG: UPower: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
18:39:32 T:1099901136   DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.ConsoleKit was not provided by any .service files
18:39:32 T:1099901136   DEBUG: DeviceKit.Power: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
18:39:32 T:1099901136   DEBUG: UPower: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
18:39:32 T:1099901136  NOTICE: aml_present, rtn(13)
18:39:32 T:1099901136  NOTICE: load settings...
18:39:32 T:1099901136   DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/settings.xml
18:39:32 T:1099901136   DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/linux.xml
18:39:32 T:1099901136    INFO: CAESinkALSA - Unable to open device "surround71" for playback
18:39:32 T:1099901136    INFO: CAESinkALSA - Unable to open device "surround51" for playback
18:39:32 T:1099901136    INFO: CAESinkALSA - Unable to open device "surround71" for playback
18:39:32 T:1099901136    INFO: CAESinkALSA - Unable to open device "surround40" for playback
18:39:32 T:1099901136    INFO: CAESinkALSA - Unable to open device "surround51" for playback
18:39:32 T:1099901136    INFO: CAESinkALSA - Unable to open device "surround71" for playback
18:39:33 T:1099901136  NOTICE: Found 1 Lists of Devices
18:39:33 T:1099901136  NOTICE: Enumerated ALSA devices:
18:39:33 T:1099901136  NOTICE:     Device 1
18:39:33 T:1099901136  NOTICE:         m_deviceName      : @
18:39:33 T:1099901136  NOTICE:         m_displayName     : Default (AML-M1 Analog)
18:39:33 T:1099901136  NOTICE:         m_displayNameExtra:
18:39:33 T:1099901136  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
18:39:33 T:1099901136  NOTICE:         m_channels        : FL,FR
18:39:33 T:1099901136  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,32000,44100,48000,64000,88200,96000
18:39:33 T:1099901136  NOTICE:         m_dataFormats     : AE_FMT_S16NE,AE_FMT_S16LE
18:39:33 T:1099901136  NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
18:39:33 T:1099901136  NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
                                            <advancedsettings>
                                              <hidebattery>1</hidebattery>
                                              <hidecpurev>1</hidecpurev>
                                              <hidecpuserial>1</hidecpuserial>
                                              <hidetotaluptime>1</hidetotaluptime>
                                              <hidecputemp>1</hidecputemp>
                                              <showexitbutton>false</showexitbutton>
                                              <loglevel hide="false">2</loglevel>
                                              <pvr>
                                                <minvideocachelevel>50</minvideocachelevel>
                                                <minaudiocachelevel>50</minaudiocachelevel>
                                              </pvr>
                                              <video>
                                                <subsdelayrange>60</subsdelayrange>
                                                <audiodelayrange>60</audiodelayrange>
                                              </video>
                                              <network>
                                                <readbufferfactor>4.0</readbufferfactor>
                                                <buffermode>1</buffermode>
                                              </network>
                                              <audiooutput>
                                                <eac3passthrough>false</eac3passthrough>
                                                <truehdpassthrough>false</truehdpassthrough>
                                                <dtshdpassthrough>false</dtshdpassthrough>
                                                <passthroughdevice>default</passthroughdevice>
                                                <audiodevice>ALSA:@</audiodevice>
                                                <channels>1</channels>
                                                <config>2</config>
                                              </audiooutput>
                                              <videoscreen>
                                                <blankdisplays>false</blankdisplays>
                                                <vsync>2</vsync>
                                              </videoscreen>
                                              <input>
                                                <enablesystemkeys>false</enablesystemkeys>
                                                <remoteaskeyboard>true</remoteaskeyboard>
                                              </input>
                                              <powermanagement>
                                                <shutdownstate>1</shutdownstate>
                                              </powermanagement>
                                            </advancedsettings>
18:39:33 T:1099901136  NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
18:39:33 T:1099901136  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
18:39:33 T:1099901136  NOTICE: Default DVD Player: dvdplayer
18:39:33 T:1099901136  NOTICE: Default Video Player: dvdplayer
18:39:33 T:1099901136  NOTICE: Default Audio Player: paplayer
18:39:33 T:1099901136  NOTICE: Enabled debug logging due to GUI setting (2)
18:39:33 T:1099901136  NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
18:39:33 T:1099901136  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
18:39:33 T:1099901136   DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
18:39:33 T:1099901136   DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
18:39:33 T:1099901136   DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
18:39:33 T:1099901136   DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
18:39:33 T:1099901136  NOTICE: Loaded playercorefactory configuration
18:39:33 T:1099901136  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
18:39:33 T:1099901136  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
15:39:33 T:1099901136    INFO: creating subdirectories
15:39:33 T:1099901136    INFO: userdata folder: special://masterprofile/
15:39:33 T:1099901136    INFO: recording folder:
15:39:33 T:1099901136    INFO: screenshots folder:
15:39:33 T:1099901136    INFO: load language info file: special://xbmc/language/English/langinfo.xml
15:39:33 T:1099901136   DEBUG: trying to set locale to en_US.UTF-8
15:39:33 T:1099901136    INFO: global locale set to C
15:39:33 T:1099901136    INFO: load English language file, from path: special://xbmc/language/
15:39:33 T:1099901136   DEBUG: POParser: loaded 3267 strings from file /usr/share/kodi/language/English/strings.po
15:39:33 T:1108300896  NOTICE: Thread ActiveAE start, auto delete: false
15:39:33 T:1116689504  NOTICE: Thread AESink start, auto delete: false
15:39:33 T:1116689504    INFO: CActiveAESink::OpenSink - initialize sink
15:39:33 T:1116689504   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
15:39:33 T:1116689504    INFO: CAESinkALSA::Initialize - Attempting to open device "default"
15:39:33 T:1116689504    INFO: CAESinkALSA::Initialize - Opened device "default"
15:39:33 T:1116689504    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
15:39:33 T:1116689504    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
15:39:33 T:1116689504   DEBUG: CAESinkALSA::InitializeHW - Min: periodSize 16, periods 2, bufferSize 32
15:39:33 T:1116689504   DEBUG: CAESinkALSA::InitializeHW - Max: periodSize 2048, periods 512, bufferSize 8192
15:39:33 T:1116689504   DEBUG: CAESinkALSA::InitializeHW - Req: periodSize 1024, periods 8, bufferSize 8192
15:39:33 T:1116689504   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 8192
15:39:33 T:1116689504   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms
15:39:33 T:1116689504   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
15:39:33 T:1116689504   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
15:39:33 T:1116689504   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none)
15:39:33 T:1116689504   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
15:39:33 T:1116689504   DEBUG:   Output Device : Default (AML-M1 Analog)
15:39:33 T:1116689504   DEBUG:   Sample Rate   : 44100
15:39:33 T:1116689504   DEBUG:   Sample Format : AE_FMT_S16NE
15:39:33 T:1116689504   DEBUG:   Channel Count : 2
15:39:33 T:1116689504   DEBUG:   Channel Layout: FL,FR
15:39:33 T:1116689504   DEBUG:   Frames        : 1024
15:39:33 T:1116689504   DEBUG:   Frame Samples : 2048
15:39:33 T:1116689504   DEBUG:   Frame Size    : 4
15:39:33 T:1099901136  NOTICE: Running database version Addons16
15:39:33 T:1099901136   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-arm.so)
15:39:33 T:1099901136   DEBUG: Loading: /usr/lib/kodi/system/libcpluff-arm.so
15:39:34 T:1099901136    INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/kodi/addons: No such file or directory'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.pulsar has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in weather.yahoo has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.vdr.vnsi has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.argustv has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.mediaportal.tvserver has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in script.favourites has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.wmc has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.dvblink has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in script.globalsearch has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in service.skin.widgets has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.vuplus has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.mythtv has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.demo has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in service.subtitles.opensubtitles has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.iptvsimple has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.njoy has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.nextpvr has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.dvbviewer has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in script.cinema.experience has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in script.linux.nm has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in pvr.hts has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
15:39:34 T:1099901136   DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
15:39:34 T:1099901136  NOTICE: ADDONS: Using repository repository.xbmc.org
15:39:34 T:1099901136    INFO: LIRC Initialize: using: /dev/lircd
15:39:34 T:1099901136    INFO: LIRC Initialize: connect failed: No such file or directory
15:39:34 T:1099901136   DEBUG: Failed to connect to LIRC. Retry in 10s.
15:39:34 T:1099901136   DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
15:39:34 T:1099901136   DEBUG: LoadMappings - loaded node "CEC Adapter"
15:39:34 T:1099901136   DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
15:39:34 T:1099901136   DEBUG: LoadMappings - loaded node "iMON HID device"
15:39:34 T:1099901136   DEBUG: CPeripheralBusUSB - initialised udev monitor
15:39:34 T:1099901136   DEBUG: SECTION:LoadDLL(libcec.so.2)
15:39:34 T:1099901136   DEBUG: Loading: libcec.so.2
15:39:34 T:1125844064  NOTICE: Thread PeripBusCEC start, auto delete: false
15:39:34 T:1134232672  NOTICE: Thread PeripBusUSBUdev start, auto delete: false
15:39:34 T:1099901136   DEBUG: UDisks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
15:39:34 T:1099901136   DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
15:39:34 T:1099901136   DEBUG: Selected UDev as storage provider
15:39:34 T:1099901136 WARNING: aml_permissions: missing su, playback might fail
15:39:34 T:1099901136  NOTICE: InitWindowSystem: Using EGL Implementation: amlogic
15:39:34 T:1099901136  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
15:39:34 T:1099901136  NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080i @ 60.000000 Hz
15:39:34 T:1099901136  NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 60.000000 Hz
15:39:34 T:1099901136  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz
15:39:34 T:1099901136  NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080i @ 50.000000 Hz
15:39:34 T:1099901136  NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 50.000000 Hz
15:39:34 T:1099901136  NOTICE: Checking resolution 17
15:39:34 T:1099901136   DEBUG: CreateWindow: Created surface of size 1280x720
15:39:35 T:1099901136  NOTICE: GL_VENDOR = ARM
15:39:35 T:1099901136  NOTICE: GL_RENDERER = Mali-400 MP
15:39:35 T:1099901136  NOTICE: GL_VERSION = OpenGL ES 2.0
15:39:35 T:1099901136  NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
15:39:35 T:1099901136   DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
15:39:35 T:1099901136  NOTICE: GL_EXTENSIONS = GL_OES_texture_npot GL_OES_compressed_ETC1_RGB8_texture GL_OES_standard_derivatives GL_OES_EGL_image GL_OES_depth24 GL_ARM_rgba8 GL_ARM_mali_shader_binary GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_format_BGRA8888 GL_EXT_blend_minmax GL_OES_EGL_image_external GL_OES_EGL_sync
15:39:35 T:1099901136   DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
15:39:35 T:1099901136   DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
15:39:35 T:1099901136   DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x28d4300
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x28ce690
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x28d1428
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x28ed8f8
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x28f0638
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x28f0ac8
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x28f5640
15:39:35 T:1099901136   DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_rgba_oes.glsl]- Initialise successful : 0x28fa6f0
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Vertex Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GL: Pixel Shader compilation log:
15:39:35 T:1099901136   DEBUG: GL: Fragment Shader compiled successfully
15:39:35 T:1099901136   DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x28fd318
15:39:35 T:1099901136   DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window
15:39:35 T:1099901136    INFO: GLES: Maximum texture width: 4096
15:39:35 T:1099901136   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
15:39:35 T:1099901136    INFO: load default splash image: /usr/share/kodi/media/Splash.png
15:39:35 T:1099901136   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
15:39:35 T:1099901136   DEBUG: Loading: /usr/lib/kodi/system/ImageLib-arm.so
15:39:35 T:1099901136    INFO: load keymapping
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/appcommand.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/gamepad.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.Nintendo.Wii.U.Pro.Controller.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/keyboard.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/mouse.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/nobs.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/remote.xml
15:39:35 T:1099901136    INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
15:39:36 T:1099901136    INFO: Loading special://xbmc/system/keymaps/variant.stvmx.keyboard.xml
15:39:36 T:1099901136    INFO: Loading special://xbmc/system/Lircmap.xml
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'mceusb'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'XboxDVDDongle'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'Microsoft_Xbox'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'anysee'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'iMON-PAD'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'MCE_via_iMON'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'TwinHanRemote'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'linux-input-layer'
15:39:36 T:1099901136    INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'mediacenter'
15:39:36 T:1099901136    INFO: * Adding remote mapping for device 'devinput'
15:39:36 T:1099901136   DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
15:39:36 T:1099901136    INFO: GUI format 1280x720, Display 1920x1080 @ 60.00i - Full Screen
15:39:36 T:1099901136   DEBUG: guilib: Fill viewport on change for solving rendering passes
15:39:36 T:1099901136   DEBUG: SECTION:LoadDLL(libcurl.so.4)
15:39:36 T:1099901136   DEBUG: Loading: libcurl.so.4
15:39:36 T:1099901136  NOTICE: Running database version Addons16
15:39:36 T:1099901136   DEBUG: Initialize, updating databases...
15:39:36 T:1099901136  NOTICE: Running database version ViewModes6
15:39:36 T:1099901136  NOTICE: Running database version Textures13
15:39:36 T:1099901136  NOTICE: Running database version MyMusic48
15:39:36 T:1099901136  NOTICE: Running database version MyVideos90
15:39:36 T:1099901136  NOTICE: Running database version TV26
15:39:36 T:1099901136  NOTICE: Running database version Epg8
15:39:36 T:1099901136   DEBUG: Initialize, updating databases... DONE
15:39:36 T:1099901136  NOTICE: start dvd mediatype detection
15:39:36 T:1099901136    INFO: DPMS: not supported on this platform
15:39:36 T:1153979488  NOTICE: Thread DetectDVDMedia start, auto delete: false
15:39:36 T:1153979488   DEBUG: Compiled with libcdio Version 0.92
15:39:36 T:1153979488   DEBUG: Thread DetectDVDMedia 1153979488 terminating
15:39:36 T:1099901136   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
15:39:36 T:1099901136   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
15:39:36 T:1099901136    INFO: Unloading old skin ...
15:39:36 T:1099901136    INFO:   load skin from: /usr/share/kodi/addons/skin.confluence (version: 2.5.9)
15:39:36 T:1099901136    INFO:   load fonts for skin...
15:39:36 T:1099901136    INFO: Loading fonts from /usr/share/kodi/addons/skin.confluence/720p/Font.xml
15:39:36 T:1099901136   DEBUG: POParser: loaded 149 strings from file /usr/share/kodi/addons/skin.confluence/language/English/strings.po
15:39:36 T:1099901136    INFO: Loading skin includes from /usr/share/kodi/addons/skin.confluence/720p/includes.xml
15:39:37 T:1099901136    INFO:   load new skin...
15:39:37 T:1099901136    INFO: Loading user windows, path /usr/share/kodi/addons/skin.confluence/720p
15:39:37 T:1099901136   DEBUG: Load Skin XML: 30.52ms
15:39:37 T:1099901136    INFO:   initialize new skin...
15:39:37 T:1099901136   DEBUG: guilib: Fill viewport on change for solving rendering passes
15:39:37 T:1099901136    INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
15:39:37 T:1099901136   DEBUG: Load Pointer.xml: 9.75ms
15:39:37 T:1099901136   DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.confluence/media/Textures.xbt
15:39:37 T:1099901136   DEBUG: Alloc resources: 137.10ms  (10.78 ms skin load)
15:39:37 T:1099901136    INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
15:39:37 T:1099901136   DEBUG: Load DialogVolumeBar.xml: 22.64ms
15:39:37 T:1099901136   DEBUG: Alloc resources: 46.44ms  (23.74 ms skin load)
15:39:37 T:1099901136    INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
15:39:37 T:1099901136   DEBUG: Load DialogKaiToast.xml: 14.41ms
15:39:37 T:1099901136   DEBUG: Alloc resources: 15.51ms  (15.49 ms skin load)
15:39:37 T:1099901136    INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
15:39:37 T:1099901136   DEBUG: Load DialogMuteBug.xml: 3.39ms
15:39:37 T:1099901136   DEBUG: Alloc resources: 4.42ms  (4.42 ms skin load)
15:39:37 T:1099901136    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
15:39:37 T:1099901136   DEBUG: Load DialogSeekBar.xml: 51.61ms
15:39:37 T:1099901136   DEBUG: Alloc resources: 69.77ms  (53.04 ms skin load)
15:39:37 T:1099901136    INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
15:39:37 T:1099901136   DEBUG: Load DialogBusy.xml: 7.69ms
15:39:37 T:1099901136   DEBUG: Alloc resources: 8.86ms  (8.77 ms skin load)
15:39:37 T:1099901136    INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
15:39:37 T:1099901136   DEBUG: Load DialogExtendedProgressBar.xml: 8.23ms
15:39:37 T:1099901136   DEBUG: Alloc resources: 9.38ms  (9.31 ms skin load)
15:39:37 T:1099901136    INFO: Loading /usr/share/kodi/addons/skin.confluence/sounds/sounds.xml
15:39:37 T:1099901136    INFO:   skin loaded...
15:39:37 T:1099901136   DEBUG: JSONRPC: JSON schema type broadcastnext references an unknown type PVR.Details.Broadcast
15:39:37 T:1099901136 WARNING: JSONRPC: Could not parse type "PVR.Details.Channel"
15:39:37 T:1099901136    INFO: JSONRPC: Adding type "PVR.Details.Channel" to list of incomplete definitions (waiting for "PVR.Details.Broadcast")
15:39:37 T:1099901136   DEBUG: JSONRPC: JSON schema type  references an unknown type PVR.Details.Channel
15:39:37 T:1099901136   DEBUG: Invalid item definition in "items" for type channels
15:39:37 T:1099901136 WARNING: JSONRPC: Could not parse type "PVR.Details.ChannelGroup.Extended"
15:39:37 T:1099901136    INFO: JSONRPC: Adding type "PVR.Details.ChannelGroup.Extended" to list of incomplete definitions (waiting for "PVR.Details.Channel")
15:39:37 T:1099901136    INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Broadcast
15:39:37 T:1099901136    INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Channel
15:39:37 T:1099901136   DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
15:39:37 T:1099901136 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
15:39:37 T:1099901136    INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
15:39:37 T:1099901136    INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
15:39:37 T:1099901136   DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
15:39:37 T:1099901136 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
15:39:37 T:1099901136    INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
15:39:37 T:1099901136    INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
15:39:37 T:1099901136    INFO: JSONRPC v6.21.2: Successfully initialized
15:39:37 T:1099901136   DEBUG: ADDON: Starting service addons.
15:39:37 T:1099901136   DEBUG: Activating window ID: 12999
15:39:37 T:1099901136   DEBUG: ------ Window Init (Startup.xml) ------
15:39:37 T:1099901136    INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
15:39:37 T:1168168032  NOTICE: Thread LanguageInvoker start, auto delete: false
15:39:37 T:1168168032    INFO: initializing python engine.
15:39:37 T:1176556640  NOTICE: Thread LanguageInvoker start, auto delete: false
15:39:37 T:1176556640    INFO: initializing python engine.
15:39:38 T:1099901136   DEBUG: Load Startup.xml: 53.88ms
15:39:38 T:1099901136   DEBUG: Alloc resources: 54.98ms  (54.97 ms skin load)
15:39:38 T:1099901136    INFO: removing tempfiles
15:39:38 T:1099901136   DEBUG: ADDON: Starting service addons.
15:39:38 T:1099901136  NOTICE: initialize done
15:39:38 T:1099901136  NOTICE: Running the application...
15:39:38 T:1099901136   DEBUG: Activating window ID: 10000
15:39:38 T:1186985056  NOTICE: Thread LanguageInvoker start, auto delete: false
15:39:38 T:1186985056    INFO: initializing python engine.
15:39:38 T:1099901136   DEBUG: ------ Window Init () ------
15:39:38 T:1099901136   DEBUG: Alloc resources: 0.02ms  (0.02 ms skin load)
15:39:38 T:1099901136   DEBUG: ------ Window Init (Pointer.xml) ------
15:39:38 T:1099901136    INFO: GLES: Enabling VSYNC
15:39:38 T:1099901136    INFO: GLES: Selected vsync mode 10
15:39:39 T:1176556640   DEBUG: CPythonInvoker(1, /root/.kodi/addons/script.cinema.experience/service.py): start processing
15:39:39 T:1186985056   DEBUG: CPythonInvoker(2, /root/.kodi/addons/plugin.video.pulsar/service.py): start processing
15:39:39 T:1168168032   DEBUG: CPythonInvoker(0, /root/.kodi/addons/service.skin.widgets/default.py): start processing
15:39:39 T:1099901136   DEBUG: ------ Window Deinit (Startup.xml) ------
15:39:39 T:1099901136   DEBUG: ------ Window Init (Home.xml) ------
15:39:39 T:1099901136    INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
15:39:40 T:1099901136   DEBUG: Load Home.xml: 950.72ms
15:39:40 T:1176556640  NOTICE: -->Python Interpreter Initialized<--
15:39:40 T:1176556640   DEBUG: CPythonInvoker(1, /root/.kodi/addons/script.cinema.experience/service.py): the source file to load is "/root/.kodi/addons/script.cinema.experience/service.py"
15:39:40 T:1176556640   DEBUG: CPythonInvoker(1, /root/.kodi/addons/script.cinema.experience/service.py): setting the Python path to /root/.kodi/addons/script.cinema.experience:/root/.kodi/addons/script.module.simplejson/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages
15:39:40 T:1176556640   DEBUG: CPythonInvoker(1, /root/.kodi/addons/script.cinema.experience/service.py): entering source directory /root/.kodi/addons/script.cinema.experience
15:39:40 T:1176556640   DEBUG: CPythonInvoker(1, /root/.kodi/addons/script.cinema.experience/service.py): instantiating addon using automatically obtained id of "script.cinema.experience" dependent on version 2.12.0 of the xbmc.python api
15:39:40 T:1186985056  NOTICE: -->Python Interpreter Initialized<--
15:39:40 T:1186985056   DEBUG: CPythonInvoker(2, /root/.kodi/addons/plugin.video.pulsar/service.py): the source file to load is "/root/.kodi/addons/plugin.video.pulsar/service.py"
15:39:40 T:1186985056   DEBUG: CPythonInvoker(2, /root/.kodi/addons/plugin.video.pulsar/service.py): setting the Python path to /root/.kodi/addons/plugin.video.pulsar:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages
15:39:40 T:1186985056   DEBUG: CPythonInvoker(2, /root/.kodi/addons/plugin.video.pulsar/service.py): entering source directory /root/.kodi/addons/plugin.video.pulsar
15:39:40 T:1168168032  NOTICE: -->Python Interpreter Initialized<--
15:39:40 T:1168168032   DEBUG: CPythonInvoker(0, /root/.kodi/addons/service.skin.widgets/default.py): the source file to load is "/root/.kodi/addons/service.skin.widgets/default.py"
15:39:40 T:1168168032   DEBUG: CPythonInvoker(0, /root/.kodi/addons/service.skin.widgets/default.py): setting the Python path to /root/.kodi/addons/service.skin.widgets:/root/.kodi/addons/script.module.simplejson/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages
15:39:40 T:1168168032   DEBUG: CPythonInvoker(0, /root/.kodi/addons/service.skin.widgets/default.py): entering source directory /root/.kodi/addons/service.skin.widgets
15:39:40 T:1168168032   DEBUG: CPythonInvoker(0, /root/.kodi/addons/service.skin.widgets/default.py): instantiating addon using automatically obtained id of "service.skin.widgets" dependent on version 2.1.0 of the xbmc.python api
15:39:40 T:1099901136   DEBUG: Alloc resources: 1462.61ms  (971.80 ms skin load)
15:39:40 T:1221588064  NOTICE: Thread JobWorker start, auto delete: true
15:39:40 T:1221588064   DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
15:39:40 T:1221588064   DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum
15:39:40 T:1221588064   DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
15:39:40 T:1221588064   DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
15:39:40 T:1186985056   DEBUG: CPythonInvoker(2, /root/.kodi/addons/plugin.video.pulsar/service.py): instantiating addon using automatically obtained id of "plugin.video.pulsar" dependent on version 2.1.0 of the xbmc.python api
15:39:40 T:1099901136   DEBUG: no profile autoexec.py (/root/.kodi/userdata/autoexec.py) found, skipping
15:39:40 T:1099901136   DEBUG: NetworkMessage - Starting network services
15:39:40 T:1099901136   DEBUG: CZeroconfAvahi::clientCallback: client is up and running
15:39:40 T:1099901136  NOTICE: starting zeroconf publishing
15:39:40 T:1099901136  NOTICE: Webserver: Starting...
15:39:40 T:1238365280  NOTICE: Thread JobWorker start, auto delete: true
15:39:40 T:1099901136  NOTICE: WebServer: Started the webserver
15:39:40 T:1238365280   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (pivos-m1) port:80
15:39:40 T:1238365280   DEBUG: CZeroconfAvahi::addService() named: Kodi (pivos-m1) type: _http._tcp port:80
15:39:40 T:1099901136  NOTICE: starting upnp server
15:39:40 T:1263531104  NOTICE: Thread JobWorker start, auto delete: true
15:39:40 T:1263531104   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (pivos-m1) port:80
15:39:40 T:1099901136   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
15:39:40 T:1229976672   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
15:39:40 T:1263531104   DEBUG: CZeroconfAvahi::addService() named: Kodi (pivos-m1) type: _xbmc-jsonrpc-h._tcp port:80
15:39:40 T:1229976672   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
15:39:40 T:1221588064   DEBUG: RunQuery took 11 ms for 0 items query: select * from movieview  ORDER BY dateAdded desc, idMovie desc LIMIT 10
15:39:41 T:1221588064   DEBUG: RunQuery took 29 ms for 0 items query: select * from episodeview  ORDER BY dateAdded desc, idEpisode desc LIMIT 10
15:39:41 T:1221588064   DEBUG: RunQuery took 4 ms for 0 items query: select * from musicvideoview  ORDER BY dateAdded desc, idMVideo desc LIMIT 10
15:39:41 T:1099901136  NOTICE: starting upnp renderer
15:39:41 T:1221588064   DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
15:39:41 T:1099901136  NOTICE: ES: Starting event server
15:39:41 T:1099901136   ERROR: JSONRPC Server: Failed to connect to sdpd
15:39:41 T:1313862752  NOTICE: Thread EventServer start, auto delete: false
15:39:41 T:1313862752  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
15:39:41 T:1313862752  NOTICE: UDP: Listening on port 9777
15:39:41 T:1238365280   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (pivos-m1) port:9777
15:39:41 T:1238365280   DEBUG: CZeroconfAvahi::addService() named: Kodi (pivos-m1) type: _xbmc-events._udp port:9777
15:39:41 T:1099901136    INFO: JSONRPC Server: Successfully initialized
15:39:41 T:1263531104   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (pivos-m1) port:9090
15:39:41 T:1099901136   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
15:39:41 T:1322251360  NOTICE: Thread TCPServer start, auto delete: false
15:39:41 T:1229976672   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
15:39:41 T:1263531104   DEBUG: CZeroconfAvahi::addService() named: Kodi (pivos-m1) type: _xbmc-jsonrpc._tcp port:9090
15:39:41 T:1099901136   DEBUG: SECTION:LoadDLL(libshairplay.so.0)
15:39:41 T:1099901136   DEBUG: Loading: libshairplay.so.0
15:39:41 T:1229976672   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
15:39:41 T:1099901136   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
15:39:41 T:1238365280   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airtunes type: _raop._tcp name:000102030405@Kodi (pivos-m1) port:36666
15:39:41 T:1238365280   DEBUG: CZeroconfAvahi::addService() named: 000102030405@Kodi (pivos-m1) type: _raop._tcp port:36666
15:39:41 T:1099901136    INFO: AIRPLAY: Cleaning up photoassetcache
15:39:41 T:1099901136    INFO: AIRPLAY Server: Successfully initialized
15:39:41 T:1339028576  NOTICE: Thread AirPlayServer start, auto delete: false
15:39:41 T:1099901136   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
15:39:41 T:1263531104   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airplay type: _airplay._tcp name:Kodi (pivos-m1) port:36667
15:39:41 T:1099901136    INFO: Found input device /dev/input/event0
15:39:41 T:1099901136    INFO: opened device 'aml_keypad' (file name /dev/input/event0), m_bSkipNonKeyEvents 0
15:39:41 T:1099901136    INFO: Found input device /dev/input/event1
15:39:41 T:1099901136    INFO: opened device 'key_input' (file name /dev/input/event1), m_bSkipNonKeyEvents 0
15:39:41 T:1099901136    INFO: Found input device /dev/input/event2
15:39:41 T:1229976672   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
15:39:41 T:1263531104   DEBUG: CZeroconfAvahi::addService() named: Kodi (pivos-m1) type: _airplay._tcp port:36667
15:39:41 T:1229976672   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
15:39:41 T:1099901136    INFO: opened device 'Shenzhen LogoTech  2.4GHz receiver ' (file name /dev/input/event2), m_bSkipNonKeyEvents 0
15:39:41 T:1099901136    INFO: CLinuxInputDevice: auto key repeat disabled on device 'Shenzhen LogoTech  2.4GHz receiver '
15:39:41 T:1099901136    INFO: Found input device /dev/input/event3
15:39:41 T:1099901136    INFO: opened device 'Shenzhen LogoTech  2.4GHz receiver ' (file name /dev/input/event3), m_bSkipNonKeyEvents 0
15:39:41 T:1099901136   DEBUG: ------ Window Deinit (Pointer.xml) ------
15:39:43 T:1347417184  NOTICE: [plugin.video.pulsar] pulsar: starting jsonrpc service
15:39:43 T:1176556640   DEBUG: [Cinema Experience] - settings() - __init__
15:39:43 T:1176556640   DEBUG: [Cinema Experience] - settings() - start
15:39:43 T:1176556640   DEBUG: [Cinema Experience] - Reading settings.xml
15:39:43 T:1355805792  NOTICE: [plugin.video.pulsar] pulsard: starting pulsard
15:39:43 T:1168168032   DEBUG: Skin Widgets: script version 0.0.29 started
15:39:43 T:1176556640   DEBUG: [Cinema Experience] - Reading settings.xml
15:39:43 T:1168168032   DEBUG: RunQuery took 19 ms for 0 items query: select * from movieview  WHERE ((movieview.playCount IS NULL OR movieview.playCount < 1))
15:39:44 T:1168168032   DEBUG: RunQuery took 13 ms for 0 items query: select * from episodeview  WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
15:39:44 T:1168168032   DEBUG: RunQuery took 3 ms for 0 items query: select * from musicvideoview
15:39:44 T:1168168032   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
15:39:44 T:1168168032   DEBUG: GetAlbumsByWhere - query took 2 ms
15:39:44 T:1168168032   DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview  WHERE (artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist) OR artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
15:39:44 T:1168168032   DEBUG: Time to retrieve artists from dataset = 25
15:39:44 T:1176556640   DEBUG: [Cinema Experience] - Cinema Experience service script version 4.0.10 started
15:39:44 T:1168168032   DEBUG: GetSongsByWhere query = SELECT songview.* FROM songview  WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
15:39:44 T:1176556640   DEBUG: [Cinema Experience] - Reading settings.xml
15:39:44 T:1176556640   DEBUG: [Cinema Experience] - settings() - start
15:39:44 T:1099901136    INFO: LIRC Initialize: using: /dev/lircd
15:39:44 T:1099901136   DEBUG: Failed to connect to LIRC. Retry in 20s.
15:39:44 T:1176556640   DEBUG: [Cinema Experience] - Reading settings.xml
15:39:44 T:1355805792  NOTICE: Traceback (most recent call last):
15:39:44 T:1355805792  NOTICE:   File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 104, in pulsard_thread
15:39:44 T:1355805792  NOTICE:     proc = start_pulsard(stdout=subprocess.PIPE, stderr=subprocess.STDOUT)
15:39:44 T:1355805792  NOTICE:   File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 89, in start_pulsard
15:39:44 T:1355805792  NOTICE:     return subprocess.Popen(args, **kwargs)
15:39:44 T:1355805792  NOTICE:   File "/usr/lib/python2.7/subprocess.py", line 679, in __init__
15:39:44 T:1355805792  NOTICE:   File "/usr/lib/python2.7/subprocess.py", line 1249, in _execute_child
15:39:44 T:1355805792  NOTICE: OSError: [Errno 2] No such file or directory
15:39:44 T:1355805792   ERROR: Exception in thread Thread-2:
                                            Traceback (most recent call last):
                                              File "/usr/lib/python2.7/threading.py", line 551, in __bootstrap_inner
                                              File "/usr/lib/python2.7/threading.py", line 504, in run
                                              File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 104, in pulsard_thread
                                                proc = start_pulsard(stdout=subprocess.PIPE, stderr=subprocess.STDOUT)
                                              File "/root/.kodi/addons/plugin.video.pulsar/resources/site-packages/pulsar/daemon.py", line 89, in start_pulsard
                                                return subprocess.Popen(args, **kwargs)
                                              File "/usr/lib/python2.7/subprocess.py", line 679, in __init__
                                              File "/usr/lib/python2.7/subprocess.py", line 1249, in _execute_child
                                            OSError: [Errno 2] No such file or directory
15:39:45 T:1168168032   DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:01.239980
15:39:45 T:1099901136   DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
15:39:45 T:1099901136   DEBUG: OnKey: right (0xf083) pressed, action is Right
15:39:45 T:1168168032   DEBUG: RunQuery took 4 ms for 0 items query: select * from movieview  WHERE (movieview.idFile  IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
15:39:45 T:1168168032   DEBUG: RunQuery took 6 ms for 0 items query: SELECT * FROM tvshowview  WHERE ( ((tvshowview.watchedcount > 0 AND tvshowview.watchedcount < tvshowview.totalCount) OR (tvshowview.watchedcount = 0 AND EXISTS (SELECT 1 FROM episodeview WHERE episodeview.idShow = tvshowview.idShow AND episodeview.resumeTimeInSeconds > 0))))
15:39:45 T:1168168032   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
15:39:45 T:1168168032   DEBUG: GetAlbumsByWhere - query took 2 ms
15:39:45 T:1168168032   DEBUG: RunQuery took 3 ms for 0 items query: select * from musicvideoview
15:39:45 T:1168168032   DEBUG: Skin Widgets: Total time needed to request recommended queries: 0:00:00.211552
15:39:45 T:1168168032   DEBUG: RunQuery took 11 ms for 0 items query: select * from movieview  WHERE ((movieview.playCount IS NULL OR movieview.playCount = 0))
15:39:45 T:1168168032   DEBUG: RunQuery took 13 ms for 0 items query: select * from episodeview  WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
15:39:45 T:1168168032   DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideoview
15:39:45 T:1168168032   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
15:39:45 T:1168168032   DEBUG: GetAlbumsByWhere - query took 2 ms
15:39:45 T:1168168032   DEBUG: Skin Widgets: Total time needed to request recent items queries: 0:00:00.237650
15:39:45 T:1168168032   DEBUG: Skin Widgets: Total time needed for all queries: 0:00:01.694383
15:39:45 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:39:45 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:39:45 T:1099901136   DEBUG: Activating window ID: 10001
15:39:45 T:1099901136   DEBUG: ------ Window Deinit (Home.xml) ------
15:39:45 T:1099901136   DEBUG: ------ Window Init (MyPrograms.xml) ------
15:39:45 T:1099901136    INFO: Loading skin file: MyPrograms.xml, load type: KEEP_IN_MEMORY
15:39:46 T:1099901136   DEBUG: Load MyPrograms.xml: 344.13ms
15:39:46 T:1099901136   DEBUG: Alloc resources: 360.00ms  (351.76 ms skin load)
15:39:46 T:1099901136   DEBUG: CGUIMediaWindow::GetDirectory (addons://sources/executable/)
15:39:46 T:1099901136   DEBUG:   ParentPath = [addons://sources/executable/]
15:39:46 T:1355805792  NOTICE: Thread BackgroundLoader start, auto delete: false
15:39:46 T:1355805792   DEBUG: Thread BackgroundLoader 1355805792 terminating
15:39:47 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:39:47 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:39:47 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:39:47 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:39:48 T:1263531104   DEBUG: DoWork - took 126 ms to load special://masterprofile/Thumbnails/2/2fc7d948.png
15:39:48 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:39:48 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:39:48 T:1238365280   DEBUG: DoWork - took 877 ms to load special://masterprofile/Thumbnails/4/41239354.jpg
15:39:48 T:1221588064   DEBUG: DoWork - took 515 ms to load special://masterprofile/Thumbnails/5/5747ab36.jpg
15:39:48 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:39:48 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:39:48 T:1355805792  NOTICE: Thread LanguageInvoker start, auto delete: false
15:39:48 T:1355805792    INFO: initializing python engine.
15:39:48 T:1355805792   DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/script.linux.nm/default.py): start processing
15:39:49 T:1355805792  NOTICE: -->Python Interpreter Initialized<--
15:39:49 T:1355805792   DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/script.linux.nm/default.py): the source file to load is "/usr/share/kodi/addons/script.linux.nm/default.py"
15:39:49 T:1355805792   DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/script.linux.nm/default.py): setting the Python path to /usr/share/kodi/addons/script.linux.nm:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages
15:39:49 T:1355805792   DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/script.linux.nm/default.py): entering source directory /usr/share/kodi/addons/script.linux.nm
15:39:49 T:1355805792   DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/script.linux.nm/default.py): instantiating addon using automatically obtained id of "script.linux.nm" dependent on version 2.0 of the xbmc.python api
15:39:49 T:1355805792  NOTICE: [SCRIPT] 'script.linux.nm: version 12.0.0' initialized!
15:39:49 T:1099901136   DEBUG: ------ Window Init (/usr/share/kodi/addons/script.linux.nm/resources/skins/default/720p/script_linux_nm-main.xml) ------
15:39:49 T:1099901136    INFO: Loading skin file: /usr/share/kodi/addons/script.linux.nm/resources/skins/default/720p/script_linux_nm-main.xml, load type: LOAD_ON_GUI_INIT
15:39:49 T:1099901136   DEBUG: LocalizeStrings: no strings.po file exist at /usr/share/kodi/addons/script.linux.nm/resources/language/English, fallback to strings.xml
15:39:50 T:1099901136   DEBUG: Previous line repeats 14 times.
15:39:50 T:1099901136   DEBUG: Load /usr/share/kodi/addons/script.linux.nm/resources/skins/default/720p/script_linux_nm-main.xml: 186.69ms
15:39:50 T:1099901136   DEBUG: Alloc resources: 190.31ms  (189.07 ms skin load)
15:39:50 T:1099901136   DEBUG: CGUIMediaWindow::GetDirectory ()
15:39:50 T:1099901136   DEBUG:   ParentPath = []
15:39:50 T:1355805792  NOTICE: updating list
15:39:51 T:1099901136   DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
15:39:51 T:1099901136   DEBUG: OnKey: left (0xf082) pressed, action is Left
15:39:51 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:39:51 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:39:52 T:1099901136   DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
15:39:52 T:1099901136   DEBUG: OnKey: right (0xf083) pressed, action is Right
15:39:53 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:39:53 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:39:53 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:39:53 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:39:53 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:39:53 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:39:54 T:1355805792   DEBUG: LocalizeStrings: no strings.po file exist at /usr/share/kodi/addons/script.linux.nm/resources/language/English, fallback to strings.xml
15:39:56 T:1355805792  NOTICE: updating list
15:39:57 T:1099901136   DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
15:39:57 T:1099901136   DEBUG: OnKey: left (0xf082) pressed, action is Left
15:39:57 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:39:57 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:04 T:1099901136    INFO: LIRC Initialize: using: /dev/lircd
15:40:04 T:1099901136   DEBUG: Failed to connect to LIRC. Retry in 40s.
15:40:10 T:1355805792  NOTICE: updating list
15:40:11 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:11 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:12 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:12 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:12 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:12 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:13 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:13 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:15 T:1099901136   DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
15:40:15 T:1099901136   DEBUG: OnKey: up (0xf080) pressed, action is Up
15:40:16 T:1099901136   DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
15:40:16 T:1099901136   DEBUG: OnKey: right (0xf083) pressed, action is Right
15:40:17 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:17 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:17 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:17 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:17 T:1263531104   DEBUG: Thread JobWorker 1263531104 terminating (autodelete)
15:40:17 T:1221588064   DEBUG: Thread JobWorker 1221588064 terminating (autodelete)
15:40:17 T:1238365280   DEBUG: Thread JobWorker 1238365280 terminating (autodelete)
15:40:17 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:17 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:18 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:40:18 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:20 T:1099901136   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-arm.so)
15:40:20 T:1099901136   DEBUG: Unloading: /ImageLib-arm.so
15:40:20 T:1099901136   DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
15:40:20 T:1099901136   DEBUG: OnKey: left (0xf082) pressed, action is Left
15:40:21 T:1099901136   DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
15:40:21 T:1099901136   DEBUG: OnKey: right (0xf083) pressed, action is Right
15:40:21 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:21 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:22 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:22 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:22 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:22 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:23 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:40:23 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:36 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:36 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:36 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:40:36 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:36 T:1355805792    INFO: CPythonInvoker(3, /usr/share/kodi/addons/script.linux.nm/default.py): script successfully run
15:40:36 T:1355805792    INFO: Python script stopped
15:40:36 T:1355805792   DEBUG: Thread LanguageInvoker 1355805792 terminating
15:40:37 T:1099901136   DEBUG: ------ Window Deinit (/usr/share/kodi/addons/script.linux.nm/resources/skins/default/720p/script_linux_nm-main.xml) ------
15:40:37 T:1099901136   DEBUG: Keyboard: scancode: 0x0e, sym: 0x0008, unicode: 0x0008, modifier: 0x0
15:40:37 T:1099901136   DEBUG: OnKey: backspace (0xf008) pressed, action is Back
15:40:37 T:1099901136   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
15:40:38 T:1099901136   DEBUG: ------ Window Deinit (MyPrograms.xml) ------
15:40:38 T:1099901136   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
15:40:38 T:1099901136   DEBUG: ------ Window Init (Home.xml) ------
15:40:38 T:1099901136   DEBUG: Window Home.xml was already loaded
15:40:38 T:1099901136   DEBUG: Alloc resources: 13.46m
15:40:39 T:1099901136   DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
15:40:39 T:1099901136   DEBUG: OnKey: right (0xf083) pressed, action is Right
15:40:39 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:40:39 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:39 T:1099901136   DEBUG: Activating window ID: 10004
15:40:40 T:1099901136   DEBUG: ------ Window Deinit (Home.xml) ------
15:40:40 T:1099901136   DEBUG: ------ Window Init (Settings.xml) ------
15:40:40 T:1099901136    INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
15:40:40 T:1099901136   DEBUG: Load Settings.xml: 87.54ms
15:40:40 T:1099901136   DEBUG: Alloc resources: 90.60ms  (89.49 ms skin load)
15:40:40 T:1355805792  NOTICE: Thread JobWorker start, auto delete: true
15:40:40 T:1355805792   DEBUG: DoWork - took 198 ms to load special://skin/backgrounds/settings.jpg
15:40:40 T:1099901136   DEBUG: Keyboard: scancode: 0x0e, sym: 0x0008, unicode: 0x0008, modifier: 0x0
15:40:40 T:1099901136   DEBUG: OnKey: backspace (0xf008) pressed, action is Back
15:40:40 T:1099901136   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
15:40:41 T:1099901136   DEBUG: ------ Window Deinit (Settings.xml) ------
15:40:41 T:1099901136   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
15:40:41 T:1099901136   DEBUG: ------ Window Init (Home.xml) ------
15:40:41 T:1099901136   DEBUG: Window Home.xml was already loaded
15:40:41 T:1099901136   DEBUG: Alloc resources: 8.25m
15:40:41 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:41 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:42 T:1099901136   DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
15:40:42 T:1099901136   DEBUG: OnKey: left (0xf082) pressed, action is Left
15:40:43 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:40:43 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:43 T:1099901136   DEBUG: Activating window ID: 10007
15:40:43 T:1099901136   DEBUG: ------ Window Deinit (Home.xml) ------
15:40:43 T:1099901136   DEBUG: ------ Window Init (SettingsSystemInfo.xml) ------
15:40:43 T:1099901136    INFO: Loading skin file: SettingsSystemInfo.xml, load type: KEEP_IN_MEMORY
15:40:43 T:1099901136   DEBUG: Load SettingsSystemInfo.xml: 106.66ms
15:40:43 T:1099901136   DEBUG: Alloc resources: 110.35ms  (109.32 ms skin load)
15:40:43 T:1355805792    INFO: easy_aquire - Created session to http://www.google.com
15:40:44 T:1099901136    INFO: LIRC Initialize: using: /dev/lircd
15:40:44 T:1099901136   DEBUG: Failed to connect to LIRC. Giving up.
15:40:44 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:44 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:44 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:44 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:45 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:45 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:46 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:46 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:47 T:1099901136   DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
15:40:47 T:1099901136   DEBUG: OnKey: up (0xf080) pressed, action is Up
15:40:47 T:1099901136   DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
15:40:47 T:1099901136   DEBUG: OnKey: up (0xf080) pressed, action is Up
15:40:52 T:1099901136   DEBUG: Keyboard: scancode: 0x0e, sym: 0x0008, unicode: 0x0008, modifier: 0x0
15:40:52 T:1099901136   DEBUG: OnKey: backspace (0xf008) pressed, action is Back
15:40:52 T:1099901136   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
15:40:53 T:1099901136   DEBUG: ------ Window Deinit (SettingsSystemInfo.xml) ------
15:40:53 T:1099901136   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
15:40:53 T:1099901136   DEBUG: ------ Window Init (Home.xml) ------
15:40:53 T:1099901136   DEBUG: Window Home.xml was already loaded
15:40:53 T:1099901136   DEBUG: Alloc resources: 19.92m
15:40:54 T:1099901136   DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
15:40:54 T:1099901136   DEBUG: OnKey: left (0xf082) pressed, action is Left
15:40:55 T:1099901136   DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
15:40:55 T:1099901136   DEBUG: OnKey: left (0xf082) pressed, action is Left
15:40:55 T:1099901136   DEBUG: Keyboard: scancode: 0x69, sym: 0x0114, unicode: 0x0000, modifier: 0x0
15:40:55 T:1099901136   DEBUG: OnKey: left (0xf082) pressed, action is Left
15:40:56 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:56 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:56 T:1099901136   DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0
15:40:56 T:1099901136   DEBUG: OnKey: right (0xf083) pressed, action is Right
15:40:57 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:40:57 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:57 T:1099901136   DEBUG: Activating window ID: 10025
15:40:57 T:1099901136   DEBUG: ------ Window Deinit (Home.xml) ------
15:40:57 T:1099901136   DEBUG: ------ Window Init (MyVideoNav.xml) ------
15:40:57 T:1099901136    INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
15:40:58 T:1099901136   DEBUG: Load MyVideoNav.xml: 666.21ms
15:40:58 T:1099901136   DEBUG: Alloc resources: 691.90ms  (681.78 ms skin load)
15:40:58 T:1099901136   DEBUG: CGUIMediaWindow::GetDirectory (addons://sources/video/)
15:40:58 T:1099901136   DEBUG:   ParentPath = [addons://sources/video/]
15:40:58 T:1238365280  NOTICE: Thread BackgroundLoader start, auto delete: false
15:40:58 T:1238365280   DEBUG: Thread BackgroundLoader 1238365280 terminating
15:40:59 T:1099901136   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
15:40:59 T:1099901136   DEBUG: OnKey: down (0xf081) pressed, action is Down
15:40:59 T:1238365280  NOTICE: Thread JobWorker start, auto delete: true
15:40:59 T:1238365280   DEBUG: DoWork - took 219 ms to load special://masterprofile/Thumbnails/1/187fadff.jpg
15:40:59 T:1238365280   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
15:40:59 T:1238365280   DEBUG: Loading: /usr/lib/kodi/system/ImageLib-arm.so
15:40:59 T:1355805792   DEBUG: DoWork - took 405 ms to load special://masterprofile/Thumbnails/b/bffb18bf.jpg
15:40:59 T:1099901136   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
15:40:59 T:1099901136   DEBUG: OnKey: return (0xf00d) pressed, action is Select
15:40:59 T:1099901136   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.pulsar/)
15:40:59 T:1099901136   DEBUG:   ParentPath = [addons://sources/video/]
15:40:59 T:1355805792   DEBUG: StartScript - calling plugin Pulsar('plugin://plugin.video.pulsar/','1','')
15:40:59 T:1355805792   DEBUG: WaitOnScriptResult - waiting on the Pulsar (id=4) plugin...
15:40:59 T:1221588064  NOTICE: Thread LanguageInvoker start, auto delete: false
15:40:59 T:1221588064    INFO: initializing python engine.
15:40:59 T:1221588064   DEBUG: CPythonInvoker(4, /root/.kodi/addons/plugin.video.pulsar/navigation.py): start processing
15:41:00 T:1221588064  NOTICE: -->Python Interpreter Initialized<--
15:41:00 T:1221588064   DEBUG: CPythonInvoker(4, /root/.kodi/addons/plugin.video.pulsar/navigation.py): the source file to load is "/root/.kodi/addons/plugin.video.pulsar/navigation.py"
15:41:00 T:1221588064   DEBUG: CPythonInvoker(4, /root/.kodi/addons/plugin.video.pulsar/navigation.py): setting the Python path to /root/.kodi/addons/plugin.video.pulsar:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages
15:41:00 T:1221588064   DEBUG: CPythonInvoker(4, /root/.kodi/addons/plugin.video.pulsar/navigation.py): entering source directory /root/.kodi/addons/plugin.video.pulsar
15:41:00 T:1221588064   DEBUG: CPythonInvoker(4, /root/.kodi/addons/plugin.video.pulsar/navigation.py): instantiating addon using automatically obtained id of "plugin.video.pulsar" dependent on version 2.1.0 of the xbmc.python api
15:41:00 T:1099901136   DEBUG: ------ Window Init (DialogBusy.xml) ------
15:41:00 T:1099901136   DEBUG: Window DialogBusy.xml was already loaded
15:41:00 T:1099901136   DEBUG: Alloc resources: 0.02m
15:41:00 T:1238365280   DEBUG: Recaching image '/root/.kodi/addons/plugin.video.pulsar/icon.png' to '1/187fadff.jpg':
15:41:00 T:1221588064    INFO: CPythonInvoker(4, /root/.kodi/addons/plugin.video.pulsar/navigation.py): script successfully run
15:41:00 T:1099901136   DEBUG: ------ Window Init (DialogKaiToast.xml) ------
15:41:00 T:1099901136   DEBUG: Window DialogKaiToast.xml was already loaded
15:41:00 T:1099901136   DEBUG: Alloc resources: 0.02m
15:41:00 T:1221588064    INFO: Python script stopped
15:41:00 T:1221588064   DEBUG: Thread LanguageInvoker 1221588064 terminating
15:41:01 T:1355805792   DEBUG:  WaitOnScriptResult - plugin exited prematurely - terminating
15:41:01 T:1099901136   ERROR: GetDirectory - Error getting plugin://plugin.video.pulsar/
15:41:01 T:1099901136   ERROR: CGUIMediaWindow::GetDirectory(plugin://plugin.video.pulsar/) failed
15:41:01 T:1099901136   DEBUG: CGUIMediaWindow::GetDirectory (addons://sources/video/)
15:41:01 T:1099901136   DEBUG:   ParentPath = [addons://sources/]
15:41:01 T:1221588064  NOTICE: Thread BackgroundLoader start, auto delete: false
15:41:01 T:1238365280   DEBUG: cached image 'special://masterprofile/Thumbnails/1/187fadff.jpg' size 720x720
15:41:01 T:1221588064   DEBUG: Thread BackgroundLoader 1221588064 terminating
15:41:01 T:1099901136   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
15:41:01 T:1355805792   DEBUG: Recaching image '/root/.kodi/addons/plugin.video.pulsar/fanart.jpg' to 'b/bffb18bf.jpg':
15:41:01 T:1355805792   DEBUG: cached image 'special://masterprofile/Thumbnails/b/bffb18bf.jpg' size 1280x720
15:41:06 T:1099901136   DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
15:41:15 T:1099901136    INFO: CheckIdle - Closing session to http://www.google.com (easy=0x340b5c8, multi=(nil))
15:41:30 T:1099901136   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-arm.so)
15:41:30 T:1099901136   DEBUG: Unloading: /ImageLib-arm.so
15:41:31 T:1238365280   DEBUG: Thread JobWorker 1238365280 terminating (autodelete)
15:41:32 T:1355805792   DEBUG: Thread JobWorker 1355805792 terminating (autodelete)
inieto commented 9 years ago

The file is present, but somehow not recognized

[root@pivos-m1 ~]# /root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm/pulsar
-sh: /root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm/pulsar: not found

[root@pivos-m1 ~]# ls -lh /root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm/pulsar
-rwxrwxrwx    1 root     root       12.2M Feb  1 18:34 /root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm/pulsar*

[root@pivos-m1 ~]# strace -t /root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm/pulsar
22:03:02 execve("/root/.kodi/userdata/addon_data/plugin.video.pulsar/bin/linux_arm/pulsar", ["/root/.kodi/userdata/addon_data/"...], [/* 20 vars */]) = -1 ENOENT (No such file or directory)
22:03:02 write(2, "strace: exec: No such file or di"..., 40strace: exec: No such file or directory
) = 40
22:03:02 exit_group(1)                  = ?
22:03:02 +++ exited with 1 +++
baelen-git commented 9 years ago

I have the same. Also still in 0.4.7

Going to downgrade to 0.4.3

inieto commented 9 years ago

@sjimmie did you have luck with 0.4.3?

baelen-git commented 9 years ago

@inieto I just copied the binary from an older version into the correct directory and now it's working.

inieto commented 9 years ago

@sjimmie thanks! I'll try that too