Skip to content

Instantly share code, notes, and snippets.

@evanpurkhiser

evanpurkhiser/pertinate-error.log

Created January 16, 2014 20:42
Show Gist options
  • Save evanpurkhiser/8463029 to your computer and use it in GitHub Desktop.
Save evanpurkhiser/8463029 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
pertinate-error.log
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:alsa_output.pci-0000_00_01.1.hdmi-stereo
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context ready
15:36:43 T:140184971536128 DEBUG: PulseAudio: Stream failed
15:36:43 T:140184971536128 ERROR: PulseAudio: Waited for the stream but it failed
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context ready
15:36:43 T:140184971536128 DEBUG: PulseAudio: Stream failed
15:36:43 T:140184971536128 ERROR: PulseAudio: Waited for the stream but it failed
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - open NULL sink
15:36:43 T:140184963143424 NOTICE: Thread AESinkNull start, auto delete: false
15:36:43 T:140184963143424 DEBUG: CAESinkNULL::Process
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - NULL Initialized:
15:36:43 T:140184971536128 DEBUG: Output Device : Default
15:36:43 T:140184971536128 DEBUG: Sample Rate : 48000
15:36:43 T:140184971536128 DEBUG: Sample Format : AE_FMT_S16NE
15:36:43 T:140184971536128 DEBUG: Channel Count : 2
15:36:43 T:140184971536128 DEBUG: Channel Layout: RAW,RAW
15:36:43 T:140184971536128 DEBUG: Frames : 12000
15:36:43 T:140184971536128 DEBUG: Frame Samples : 2
15:36:43 T:140184971536128 DEBUG: Frame Size : 4
Raw
xbmc.log
15:36:17 T:140185377363904 NOTICE: special://profile/ is mapped to: special://masterprofile/
15:36:17 T:140185377363904 NOTICE: -----------------------------------------------------------------------
15:36:17 T:140185377363904 NOTICE: Starting XBMC (13.0-ALPHA12 Git:20140115-63e652f). Platform: x86 Linux 64-bit
15:36:17 T:140185377363904 NOTICE: Using Debug XBMC x64 build, compiled Jan 16 2014 by GCC 4.8.2 for x86 Linux 64-bit 3.12.4
15:36:17 T:140185377363904 NOTICE: Running on Linux 64-bit (Arch Linux, 3.12.7-2-ARCH x86_64)
15:36:17 T:140185377363904 NOTICE: Host CPU: AMD A6-3500 APU with Radeon(tm) HD Graphics, 3 cores available
15:36:17 T:140185377363904 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
15:36:17 T:140185377363904 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
15:36:17 T:140185377363904 NOTICE: special://masterprofile/ is mapped to: /home/evan/.local/share/xbmc/userdata
15:36:17 T:140185377363904 NOTICE: special://home/ is mapped to: /home/evan/.local/share/xbmc
15:36:17 T:140185377363904 NOTICE: special://temp/ is mapped to: /home/evan/.cache/xbmc
15:36:17 T:140185377363904 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
15:36:17 T:140185377363904 NOTICE: Local hostname: htpc
15:36:17 T:140185377363904 NOTICE: Log File is located: /home/evan/.cache/xbmc/xbmc.log
15:36:17 T:140185377363904 NOTICE: -----------------------------------------------------------------------
15:36:17 T:140185377363904 DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.ConsoleKit was not provided by any .service files
15:36:17 T:140185377363904 DEBUG: UPower: org.freedesktop.DBus.Error.AccessDenied - Rejected send message, 1 matched rules; type="method_call", sender=":1.173" (uid=1000 pid=30830 comm="/usr/lib/xbmc/xbmc.bin ") interface="org.freedesktop.UPower" member="EnumerateDevices" error name="(unset)" requested_reply="0" destination="org.freedesktop.UPower" (uid=0 pid=256 comm="/usr/lib/upower/upowerd ")
15:36:17 T:140185377363904 DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.ConsoleKit was not provided by any .service files
15:36:17 T:140185377363904 DEBUG: DeviceKit.Power: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
15:36:17 T:140185377363904 INFO: Selected Logind/UPower as PowerSyscall
15:36:17 T:140185377363904 ERROR: DBus: Error org.freedesktop.DBus.Error.AccessDenied - Rejected send message, 1 matched rules; type="method_call", sender=":1.173" (uid=1000 pid=30830 comm="/usr/lib/xbmc/xbmc.bin ") interface="org.freedesktop.UPower" member="EnumerateDevices" error name="(unset)" requested_reply="0" destination="org.freedesktop.UPower" (uid=0 pid=256 comm="/usr/lib/upower/upowerd ")
15:36:17 T:140185377363904 INFO: LogindUPowerSyscall - UPower not found, battery information will not be available
15:36:17 T:140185377363904 DEBUG: LogindUPowerSyscall - inhibit lock taken, fd 11
15:36:17 T:140185377363904 NOTICE: load settings...
15:36:17 T:140185377363904 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/settings.xml
15:36:17 T:140185377363904 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/linux.xml
15:36:18 T:140185377363904 DEBUG: PulseAudio: Context authorizing
15:36:18 T:140185377363904 DEBUG: PulseAudio: Context setting name
15:36:18 T:140185377363904 DEBUG: PulseAudio: Context ready
15:36:18 T:140184923256576 DEBUG: PulseAudio: Found Built-in Audio Digital Stereo (HDMI) with devicestring alsa_output.pci-0000_00_01.1.hdmi-stereo
15:36:18 T:140185377363904 NOTICE: Found 1 Lists of Devices
15:36:18 T:140185377363904 NOTICE: Enumerated PULSE devices:
15:36:18 T:140185377363904 NOTICE: Device 1
15:36:18 T:140185377363904 NOTICE: m_deviceName : Default
15:36:18 T:140185377363904 NOTICE: m_displayName : Default
15:36:18 T:140185377363904 NOTICE: m_displayNameExtra: Default Output Device (PULSEAUDIO)
15:36:18 T:140185377363904 NOTICE: m_deviceType : AE_DEVTYPE_PCM
15:36:18 T:140185377363904 NOTICE: m_channels : FL,FR
15:36:18 T:140185377363904 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
15:36:18 T:140185377363904 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_S16NE,AE_FMT_S24LE3,AE_FMT_S24BE3,AE_FMT_S24NE3,AE_FMT_S24LE4,AE_FMT_S24BE4,AE_FMT_S24NE4,AE_FMT_S32BE,AE_FMT_S32LE,AE_FMT_S32NE,AE_FMT_FLOAT
15:36:18 T:140185377363904 NOTICE: Device 2
15:36:18 T:140185377363904 NOTICE: m_deviceName : alsa_output.pci-0000_00_01.1.hdmi-stereo
15:36:18 T:140185377363904 NOTICE: m_displayName : Built-in Audio Digital Stereo (HDMI)
15:36:18 T:140185377363904 NOTICE: m_displayNameExtra: HDMI / DisplayPort (PULSEAUDIO)
15:36:18 T:140185377363904 NOTICE: m_deviceType : AE_DEVTYPE_IEC958
15:36:18 T:140185377363904 NOTICE: m_channels : FL,FR
15:36:18 T:140185377363904 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
15:36:18 T:140185377363904 NOTICE: m_dataFormats : AE_FMT_AC3,AE_FMT_DTS,AE_FMT_U8,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_S16NE,AE_FMT_S24LE3,AE_FMT_S24BE3,AE_FMT_S24NE3,AE_FMT_S24LE4,AE_FMT_S24BE4,AE_FMT_S24NE4,AE_FMT_S32BE,AE_FMT_S32LE,AE_FMT_S32NE,AE_FMT_FLOAT
15:36:18 T:140185377363904 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
15:36:18 T:140185377363904 NOTICE: Loaded settings file from special://profile/advancedsettings.xml
15:36:18 T:140185377363904 NOTICE: Contents of special://profile/advancedsettings.xml are...
<advancedsettings>
<splash>false</splash>
<loglevel>1</loglevel>
<tvshowmatching>
<regexp>S([0-9]+)-Episode([0-9]+)\..*</regexp>
</tvshowmatching>
<pathsubstitution>
<substitute>
<from>special://xbmc/sounds</from>
<to>special://home/sounds</to>
</substitute>
</pathsubstitution>
<algorithmdirtyregions>1</algorithmdirtyregions>
</advancedsettings>
15:36:18 T:140185377363904 NOTICE: Log level changed to 1
15:36:18 T:140185377363904 DEBUG: Configuring path substitutions
15:36:18 T:140185377363904 DEBUG: Registering substition pair:
15:36:18 T:140185377363904 DEBUG: From: [/usr/share/xbmc/sounds]
15:36:18 T:140185377363904 DEBUG: To: [special://home/sounds]
15:36:18 T:140185377363904 NOTICE: Default DVD Player: dvdplayer
15:36:18 T:140185377363904 NOTICE: Default Video Player: dvdplayer
15:36:18 T:140185377363904 NOTICE: Default Audio Player: paplayer
15:36:18 T:140185377363904 NOTICE: Disabled debug logging due to GUI setting. Level 1.
15:36:18 T:140185377363904 NOTICE: Log level changed to 1
15:36:18 T:140185377363904 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
15:36:18 T:140185377363904 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
15:36:18 T:140185377363904 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
15:36:18 T:140185377363904 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
15:36:18 T:140185377363904 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
15:36:18 T:140185377363904 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
15:36:18 T:140185377363904 NOTICE: Loaded playercorefactory configuration
15:36:18 T:140185377363904 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
15:36:18 T:140185377363904 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
15:36:18 T:140185377363904 NOTICE: LoadFromXML - unable to load:/home/evan/.local/share/xbmc/userdata/wakeonlan.xml
15:36:18 T:140185377363904 INFO: creating subdirectories
15:36:18 T:140185377363904 INFO: userdata folder: special://masterprofile/
15:36:18 T:140185377363904 INFO: recording folder:
15:36:18 T:140185377363904 INFO: screenshots folder:
15:36:18 T:140185377363904 INFO: load language info file: special://xbmc/language/English/langinfo.xml
15:36:18 T:140185377363904 DEBUG: trying to set locale to en_US.UTF-8
15:36:18 T:140185377363904 INFO: global locale set to en_US.UTF-8
15:36:18 T:140185377363904 INFO: load English language file, from path: special://xbmc/language/
15:36:18 T:140185377363904 DEBUG: POParser: loaded 3128 strings from file /usr/share/xbmc/language/English/strings.po
15:36:18 T:140185377363904 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-52-x86_64-linux.so)
15:36:18 T:140185377363904 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avutil-52-x86_64-linux.so
15:36:18 T:140185377363904 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-54-x86_64-linux.so)
15:36:18 T:140185377363904 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcodec-54-x86_64-linux.so
15:36:18 T:140185377363904 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-54-x86_64-linux.so)
15:36:18 T:140185377363904 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avformat-54-x86_64-linux.so
15:36:18 T:140184923256576 NOTICE: Thread ActiveAE start, auto delete: false
15:36:18 T:140184971536128 NOTICE: Thread AESink start, auto delete: false
15:36:18 T:140184971536128 INFO: CActiveAESink::OpenSink - initialize sink
15:36:18 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
15:36:18 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:36:18 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:36:18 T:140184971536128 DEBUG: PulseAudio: Context ready
15:36:18 T:140184971536128 DEBUG: PulseAudio: Stream ready
15:36:18 T:140184971536128 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
15:36:18 T:140184971536128 DEBUG: Output Device : Default
15:36:18 T:140184971536128 DEBUG: Sample Rate : 44100
15:36:18 T:140184971536128 DEBUG: Sample Format : AE_FMT_FLOAT
15:36:18 T:140184971536128 DEBUG: Channel Count : 6
15:36:18 T:140184971536128 DEBUG: Channel Layout: FL,FR,FC,BL,BR,LFE
15:36:18 T:140184971536128 DEBUG: Frames : 500
15:36:18 T:140184971536128 DEBUG: Frame Samples : 3000
15:36:18 T:140184971536128 DEBUG: Frame Size : 24
15:36:18 T:140185377363904 NOTICE: Running database version Addons16
15:36:18 T:140185377363904 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
15:36:18 T:140185377363904 DEBUG: Loading: /usr/lib/xbmc/system/libcpluff-x86_64-linux.so
15:36:18 T:140185377363904 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/xbmc/addons: No such file or directory'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in visualization.fishbmc has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in weather.wunderground has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in skin.xperience1080 has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.favourites has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.playalbum has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.globalsearch has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.tvtunes has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.randomandlastitems has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in service.skin.widgets has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.module.simple.downloader has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.artistslideshow has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.artwork.downloader has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.debug has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.videolanguage has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.tv.show.next.aired has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in script.module.elementtree has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
15:36:18 T:140185377363904 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
15:36:18 T:140185377363904 NOTICE: ADDONS: Using repository repository.xbmc.org
15:36:18 T:140185377363904 INFO: LIRC Initialize: using: /dev/lircd
15:36:18 T:140185377363904 INFO: LIRC Initialize: successfully started
15:36:18 T:140185377363904 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
15:36:18 T:140185377363904 DEBUG: LoadMappings - loaded node "CEC Adapter"
15:36:18 T:140185377363904 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
15:36:18 T:140185377363904 DEBUG: LoadMappings - loaded node "iMON HID device"
15:36:18 T:140185377363904 DEBUG: CPeripheralBusUSB - initialised udev monitor
15:36:18 T:140185377363904 DEBUG: SECTION:LoadDLL(libcec.so.2)
15:36:18 T:140185377363904 DEBUG: Loading: libcec.so.2
15:36:18 T:140185377363904 ERROR: Unable to load libcec.so.2, reason: libcec.so.2: cannot open shared object file: No such file or directory
15:36:18 T:140185377363904 DEBUG: Dll libcec.so.2 was not found in path
15:36:18 T:140185377363904 ERROR: Initialise - failed to initialise bus cec
15:36:18 T:140184950302464 NOTICE: Thread PeripBusUSBUdev start, auto delete: false
15:36:18 T:140185377363904 DEBUG: UDisks: org.freedesktop.DBus.Error.AccessDenied - Rejected send message, 1 matched rules; type="method_call", sender=":1.173" (uid=1000 pid=30830 comm="/usr/lib/xbmc/xbmc.bin ") interface="org.freedesktop.UDisks" member="EnumerateDevices" error name="(unset)" requested_reply="0" destination="org.freedesktop.UDisks" (uid=0 pid=345 comm="/usr/lib/udisks/udisks-daemon ")
15:36:18 T:140185377363904 DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
15:36:18 T:140185377363904 DEBUG: Selected UDev as storage provider
15:36:18 T:140185377363904 NOTICE: Setup SDL
15:36:19 T:140185377363904 INFO: Available videomodes (xrandr):
15:36:19 T:140185377363904 INFO: Number of connected outputs: 1
15:36:19 T:140185377363904 INFO: Output 'HDMI-0' has 23 modes
15:36:19 T:140185377363904 INFO: ID:0x54 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x55 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x56 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x57 Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x5a Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x5c Name:1920x1080 Refresh:23.976080 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x58 Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x59 Name:1920x1080i Refresh:25.000000 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x5b Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x5d Name:1280x1024 Refresh:60.019741 Width:1280 Height:1024
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.422562
15:36:19 T:140185377363904 INFO: ID:0x5e Name:1280x720 Refresh:60.000000 Width:1280 Height:720
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x5f Name:1280x720 Refresh:50.000000 Width:1280 Height:720
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x60 Name:1280x720 Refresh:59.940201 Width:1280 Height:720
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.000239
15:36:19 T:140185377363904 INFO: ID:0x61 Name:1440x576i Refresh:25.000000 Width:1440 Height:576
15:36:19 T:140185377363904 INFO: Pixel Ratio: 0.711281
15:36:19 T:140185377363904 INFO: ID:0x62 Name:1024x768 Refresh:60.003841 Width:1024 Height:768
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.333652
15:36:19 T:140185377363904 INFO: ID:0x63 Name:800x600 Refresh:60.316540 Width:800 Height:600
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.333652
15:36:19 T:140185377363904 INFO: ID:0x64 Name:720x576 Refresh:50.000000 Width:720 Height:576
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.422562
15:36:19 T:140185377363904 INFO: ID:0x65 Name:720x480 Refresh:60.000000 Width:720 Height:480
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.185468
15:36:19 T:140185377363904 INFO: ID:0x66 Name:720x480 Refresh:59.940060 Width:720 Height:480
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.185468
15:36:19 T:140185377363904 INFO: ID:0x67 Name:640x480 Refresh:60.000000 Width:640 Height:480
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.333652
15:36:19 T:140185377363904 INFO: ID:0x68 Name:640x480 Refresh:59.940479 Width:640 Height:480
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.333652
15:36:19 T:140185377363904 INFO: ID:0x69 Name:640x480 Refresh:59.940479 Width:640 Height:480
15:36:19 T:140185377363904 INFO: Pixel Ratio: 1.333652
15:36:19 T:140185377363904 INFO: ID:0x6a Name:720x400 Refresh:70.081657 Width:720 Height:400
15:36:19 T:140185377363904 INFO: Pixel Ratio: 0.987890
15:36:19 T:140185377363904 NOTICE: Checking resolution 16
15:36:19 T:140185377363904 DEBUG: OnLostDevice - notify display change event
15:36:19 T:140185377363904 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI-0 --mode 0x54
15:36:19 T:140185377363904 NOTICE: Using visual 0x25b
15:36:19 T:140185377363904 INFO: GL: Maximum texture width: 16384
15:36:19 T:140185377363904 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so)
15:36:19 T:140185377363904 DEBUG: Loading: /usr/lib/xbmc/system/ImageLib-x86_64-linux.so
15:36:19 T:140185377363904 DEBUG: GLX_EXTENSIONS: GLX_ARB_create_context GLX_ARB_create_context_profile GLX_ARB_fbconfig_float GLX_ARB_framebuffer_sRGB GLX_ARB_get_proc_address GLX_ARB_multisample GLX_EXT_import_context GLX_EXT_visual_info GLX_EXT_visual_rating GLX_EXT_framebuffer_sRGB GLX_EXT_create_context_es2_profile GLX_MESA_copy_sub_buffer GLX_MESA_multithread_makecurrent GLX_MESA_swap_control GLX_OML_swap_method GLX_OML_sync_control GLX_SGI_make_current_read GLX_SGI_swap_control GLX_SGI_video_sync GLX_SGIS_multisample GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGIX_visual_select_group GLX_EXT_texture_from_pixmap GLX_INTEL_swap_event
15:36:19 T:140185377363904 NOTICE: GL_VENDOR = X.Org
15:36:19 T:140185377363904 NOTICE: GL_RENDERER = Gallium 0.4 on AMD SUMO
15:36:19 T:140185377363904 NOTICE: GL_VERSION = 3.0 Mesa 10.0.2
15:36:19 T:140185377363904 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
15:36:19 T:140185377363904 NOTICE: GL_EXTENSIONS = GL_ARB_multisample GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_copy_texture GL_EXT_polygon_offset GL_EXT_subtexture GL_EXT_texture_object GL_EXT_vertex_array GL_EXT_compiled_vertex_array GL_EXT_texture GL_EXT_texture3D GL_IBM_rasterpos_clip GL_ARB_point_parameters GL_EXT_draw_range_elements GL_EXT_packed_pixels GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_separate_specular_color GL_EXT_texture_edge_clamp GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_ARB_framebuffer_sRGB GL_ARB_multitexture GL_EXT_framebuffer_sRGB GL_IBM_multimode_draw_arrays GL_IBM_texture_mirrored_repeat GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_transpose_matrix GL_EXT_blend_func_separate GL_EXT_fog_coord GL_EXT_multi_draw_arrays GL_EXT_secondary_color GL_EXT_texture_env_add GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod_bias GL_INGR_blend_func_separate GL_NV_blend_square GL_NV_light_max_exponent GL_NV_texgen_reflection GL_NV_texture_env_combine4 GL_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_ARB_depth_texture GL_ARB_occlusion_query GL_ARB_shadow GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_mirrored_repeat GL_ARB_window_pos GL_EXT_stencil_two_side GL_EXT_texture_cube_map GL_NV_depth_clamp GL_NV_fog_distance GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_shader GL_ARB_shader_objects GL_ARB_vertex_program GL_ARB_vertex_shader GL_ATI_draw_buffers GL_ATI_texture_env_combine3 GL_ATI_texture_float GL_EXT_shadow_funcs GL_EXT_stencil_wrap GL_MESA_pack_invert GL_NV_primitive_restart GL_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel GL_ARB_occlusion_query2 GL_ARB_point_sprite GL_ARB_shading_language_100 GL_ARB_sync GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ATI_blend_equation_separate GL_EXT_blend_equation_separate GL_OES_read_format GL_ARB_color_buffer_float GL_ARB_pixel_buffer_object GL_ARB_texture_compression_rgtc GL_ARB_texture_float GL_ARB_texture_rectangle GL_ATI_texture_compression_3dc GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_rgtc GL_EXT_texture_mirror_clamp GL_EXT_texture_rectangle GL_EXT_texture_sRGB GL_EXT_texture_shared_exponent GL_ARB_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_packed_depth_stencil GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_ATI_texture_mirror_once GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_compression_latc GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_MESA_texture_array GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_conservative_depth GL_AMD_draw_buffers_blend GL_AMD_seamless_cubemap_per_texture GL_AMD_shader_stencil_export GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_stencil_export GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_multisample GL_ARB_texture_rgb10_a2ui GL_ARB_uniform_buffer_object GL_ARB_vertex_type_2_10_10_10_rev GL_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_NV_texture_barrier GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_shader_bit_encoding GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_NV_vdpau_interop GL_ARB_base_instance GL_ARB_conservative_depth GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_shading_language_420pack GL_ARB_shading_language_packing GL_ARB_texture_storage GL_ARB_transform_feedback_instanced GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_transform_feedback GL_ARB_invalidate_subdata GL_ARB_texture_storage_multisample GL_ARB_vertex_attrib_binding GL_KHR_debug GL_ARB_texture_mirror_clamp_to_edge GL_ARB_vertex_type_10f_11f_11f_rev
15:36:19 T:140185377363904 DEBUG: OnLostDevice - notify display change event
15:36:19 T:140185377363904 ERROR: GLX: Same window as before, refreshing context
15:36:19 T:140185377363904 INFO: GL: Maximum texture width: 16384
15:36:19 T:140185377363904 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
15:36:19 T:140185377363904 INFO: load keymapping
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/joystick.xml
15:36:19 T:140185377363904 NOTICE: No Joystick name specified, loading default map
15:36:19 T:140185377363904 NOTICE: Previous line repeats 35 times.
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/mouse.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/remote.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
15:36:19 T:140185377363904 INFO: Loading special://masterprofile/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
15:36:19 T:140185377363904 INFO: Loading special://profile/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
15:36:19 T:140185377363904 INFO: Loading special://xbmc/system/Lircmap.xml
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'mceusb'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'XboxDVDDongle'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'anysee'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'iMON-PAD'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'MCE_via_iMON'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'TwinHanRemote'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'linux-input-layer'
15:36:19 T:140185377363904 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
15:36:19 T:140185377363904 INFO: * Linking remote mapping for 'linux-input-layer' to 'devinput'
15:36:19 T:140185377363904 INFO: * Adding remote mapping for device 'mediacenter'
15:36:19 T:140185377363904 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
15:36:19 T:140185377363904 INFO: GUI format 1920x1080, Display 1920x1080@ 60.00 - Full Screen
15:36:19 T:140185377363904 DEBUG: guilib: Fill viewport on change for solving rendering passes
15:36:19 T:140185377363904 DEBUG: SECTION:LoadDLL(libcurl.so.4)
15:36:19 T:140185377363904 DEBUG: Loading: libcurl.so.4
15:36:19 T:140185377363904 NOTICE: Running database version Addons16
15:36:19 T:140185377363904 DEBUG: Initialize, updating databases...
15:36:19 T:140185377363904 NOTICE: Running database version ViewModes6
15:36:19 T:140185377363904 NOTICE: Running database version Textures13
15:36:19 T:140185377363904 NOTICE: Running database version MyMusic44
15:36:19 T:140185377363904 NOTICE: Running database version MyVideos77
15:36:19 T:140185377363904 NOTICE: Running database version TV22
15:36:19 T:140185377363904 NOTICE: Running database version Epg7
15:36:19 T:140185377363904 DEBUG: Initialize, updating databases... DONE
15:36:19 T:140185377363904 NOTICE: start dvd mediatype detection
15:36:19 T:140185377363904 NOTICE: initializing playlistplayer
15:36:19 T:140185377363904 NOTICE: DONE initializing playlistplayer
15:36:19 T:140184941778688 NOTICE: Thread DetectDVDMedia start, auto delete: false
15:36:19 T:140184941778688 DEBUG: Compiled with libcdio Version 0.90
15:36:19 T:140185377363904 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
15:36:19 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
15:36:19 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
15:36:19 T:140185377363904 INFO: Unloading old skin ...
15:36:19 T:140185377363904 INFO: load skin from: /usr/share/xbmc/addons/skin.confluence (version: 2.2.7)
15:36:19 T:140185377363904 INFO: load fonts for skin...
15:36:19 T:140185377363904 INFO: Loading fonts from /usr/share/xbmc/addons/skin.confluence/720p/Font.xml
15:36:19 T:140185377363904 DEBUG: POParser: loaded 148 strings from file /usr/share/xbmc/addons/skin.confluence/language/English/strings.po
15:36:19 T:140185377363904 INFO: Loading skin includes from /usr/share/xbmc/addons/skin.confluence/720p/includes.xml
15:36:19 T:140185377363904 INFO: load new skin...
15:36:19 T:140185377363904 INFO: Loading user windows, path /usr/share/xbmc/addons/skin.confluence/720p
15:36:19 T:140185377363904 DEBUG: Load Skin XML: 2.04ms
15:36:19 T:140185377363904 INFO: initialize new skin...
15:36:19 T:140185377363904 DEBUG: guilib: Fill viewport on change for solving rendering passes
15:36:19 T:140185377363904 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
15:36:19 T:140185377363904 DEBUG: Load Pointer.xml: 0.49ms
15:36:19 T:140185377363904 DEBUG: OpenBundle - Opened bundle /usr/share/xbmc/addons/skin.confluence/media/Textures.xbt
15:36:19 T:140185377363904 DEBUG: Alloc resources: 1.51ms (0.51 ms skin load)
15:36:19 T:140185377363904 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
15:36:19 T:140185377363904 DEBUG: Load DialogVolumeBar.xml: 1.78ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 1.90ms (1.84 ms skin load)
15:36:19 T:140185377363904 INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
15:36:19 T:140185377363904 DEBUG: Load DialogKaiToast.xml: 1.04ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 1.07ms (1.07 ms skin load)
15:36:19 T:140185377363904 INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
15:36:19 T:140185377363904 DEBUG: Load DialogMuteBug.xml: 0.18ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 0.20ms (0.20 ms skin load)
15:36:19 T:140185377363904 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
15:36:19 T:140185377363904 DEBUG: Load DialogSeekBar.xml: 3.99ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 4.19ms (4.06 ms skin load)
15:36:19 T:140185377363904 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
15:36:19 T:140185377363904 DEBUG: Load DialogBusy.xml: 0.60ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 0.63ms (0.62 ms skin load)
15:36:19 T:140185377363904 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
15:36:19 T:140185377363904 DEBUG: Load DialogExtendedProgressBar.xml: 0.69ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 0.72ms (0.71 ms skin load)
15:36:19 T:140185377363904 INFO: Loading special://xbmc/sounds/crisp/sounds.xml
15:36:19 T:140184923256576 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swresample-0-x86_64-linux.so)
15:36:19 T:140184923256576 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swresample-0-x86_64-linux.so
15:36:19 T:140185377363904 INFO: skin loaded...
15:36:19 T:140185377363904 DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
15:36:19 T:140185377363904 ERROR: JSONRPC: Could not parse type "GUI.Property.Value"
15:36:19 T:140185377363904 INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
15:36:19 T:140185377363904 INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
15:36:19 T:140185377363904 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
15:36:19 T:140185377363904 ERROR: JSONRPC: Could not parse type "Setting.Details.SettingList"
15:36:19 T:140185377363904 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
15:36:19 T:140185377363904 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
15:36:19 T:140185377363904 INFO: JSONRPC v6.13.3: Successfully initialized
15:36:19 T:140185377363904 DEBUG: ADDON: Starting service addons.
15:36:19 T:140184511313664 NOTICE: Thread LanguageInvoker start, auto delete: false
15:36:19 T:140185377363904 NOTICE: Previous line repeats 1 times.
15:36:19 T:140185377363904 DEBUG: Activating window ID: 12999
15:36:19 T:140184502920960 NOTICE: Thread LanguageInvoker start, auto delete: false
15:36:19 T:140184494528256 INFO: initializing python engine.
15:36:19 T:140184486135552 INFO: Previous line repeats 1 times.
15:36:19 T:140184486135552 NOTICE: Thread LanguageInvoker start, auto delete: false
15:36:19 T:140184486135552 INFO: initializing python engine.
15:36:19 T:140185377363904 DEBUG: ------ Window Init (Startup.xml) ------
15:36:19 T:140184502920960 INFO: initializing python engine.
15:36:19 T:140185377363904 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
15:36:19 T:140185377363904 DEBUG: Load Startup.xml: 4.52ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 4.62ms (4.62 ms skin load)
15:36:19 T:140185377363904 INFO: removing tempfiles
15:36:19 T:140185377363904 DEBUG: ADDON: Starting service addons.
15:36:19 T:140185377363904 NOTICE: initialize done
15:36:19 T:140184477742848 NOTICE: Thread LanguageInvoker start, auto delete: false
15:36:19 T:140184477742848 INFO: initializing python engine.
15:36:19 T:140184494528256 DEBUG: CPythonInvoker(2, /home/evan/.local/share/xbmc/addons/script.module.simple.downloader/default.py): start processing
15:36:19 T:140184511313664 DEBUG: CPythonInvoker(0, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): start processing
15:36:19 T:140184486135552 DEBUG: CPythonInvoker(3, /home/evan/.local/share/xbmc/addons/script.artwork.downloader/service.py): start processing
15:36:19 T:140184502920960 DEBUG: CPythonInvoker(1, /home/evan/.local/share/xbmc/addons/service.skin.widgets/default.py): start processing
15:36:19 T:140184477742848 DEBUG: CPythonInvoker(4, /home/evan/.local/share/xbmc/addons/script.common.plugin.cache/default.py): start processing
15:36:19 T:140185377363904 NOTICE: Enabled Joystick: Controller (Xbox 360 Wireless Receiver for Windows)
15:36:19 T:140185377363904 NOTICE: Details: Total Axis: 5 Total Hats: 1 Total Buttons: 10
15:36:19 T:140184494528256 NOTICE: -->Python Interpreter Initialized<--
15:36:19 T:140184494528256 DEBUG: CPythonInvoker(2, /home/evan/.local/share/xbmc/addons/script.module.simple.downloader/default.py): the source file to load is /home/evan/.local/share/xbmc/addons/script.module.simple.downloader/default.py
15:36:19 T:140185377363904 NOTICE: Running the application...
15:36:19 T:140185377363904 DEBUG: ExecuteXBMCAction : Translating action string
15:36:19 T:140185377363904 DEBUG: Activating window ID: 10000
15:36:19 T:140185377363904 INFO: GL: Disabling VSYNC
15:36:19 T:140184486135552 NOTICE: -->Python Interpreter Initialized<--
15:36:19 T:140184486135552 DEBUG: CPythonInvoker(3, /home/evan/.local/share/xbmc/addons/script.artwork.downloader/service.py): the source file to load is /home/evan/.local/share/xbmc/addons/script.artwork.downloader/service.py
15:36:19 T:140185377363904 DEBUG: ------ Window Deinit (Startup.xml) ------
15:36:19 T:140184502920960 NOTICE: -->Python Interpreter Initialized<--
15:36:19 T:140184502920960 DEBUG: CPythonInvoker(1, /home/evan/.local/share/xbmc/addons/service.skin.widgets/default.py): the source file to load is /home/evan/.local/share/xbmc/addons/service.skin.widgets/default.py
15:36:19 T:140185377363904 DEBUG: CheckDisplayEvents: Received RandR event 89
15:36:19 T:140185377363904 DEBUG: CheckDisplayEvents - notify display reset event
15:36:19 T:140184486135552 DEBUG: CPythonInvoker(3, /home/evan/.local/share/xbmc/addons/script.artwork.downloader/service.py): setting the Python path to /home/evan/.local/share/xbmc/addons/script.artwork.downloader:/home/evan/.local/share/xbmc/addons/script.module.simplejson/lib:/home/evan/.local/share/xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/evan/.local/share/xbmc/addons/script.common.plugin.cache/lib:/home/evan/.local/share/xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/evan/.local/share/xbmc/addons/script.module.parsedom/lib:/home/evan/.local/share/xbmc/addons/script.module.elementtree/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:36:19 T:140184486135552 DEBUG: CPythonInvoker(3, /home/evan/.local/share/xbmc/addons/script.artwork.downloader/service.py): entering source directory /home/evan/.local/share/xbmc/addons/script.artwork.downloader
15:36:19 T:140184494528256 DEBUG: CPythonInvoker(2, /home/evan/.local/share/xbmc/addons/script.module.simple.downloader/default.py): setting the Python path to /home/evan/.local/share/xbmc/addons/script.module.simple.downloader:/home/evan/.local/share/xbmc/addons/script.module.simplejson/lib:/home/evan/.local/share/xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/evan/.local/share/xbmc/addons/script.common.plugin.cache/lib:/home/evan/.local/share/xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/evan/.local/share/xbmc/addons/script.module.parsedom/lib:/home/evan/.local/share/xbmc/addons/script.module.elementtree/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:36:19 T:140184494528256 DEBUG: CPythonInvoker(2, /home/evan/.local/share/xbmc/addons/script.module.simple.downloader/default.py): entering source directory /home/evan/.local/share/xbmc/addons/script.module.simple.downloader
15:36:19 T:140184477742848 NOTICE: -->Python Interpreter Initialized<--
15:36:19 T:140184477742848 DEBUG: CPythonInvoker(4, /home/evan/.local/share/xbmc/addons/script.common.plugin.cache/default.py): the source file to load is /home/evan/.local/share/xbmc/addons/script.common.plugin.cache/default.py
15:36:19 T:140184486135552 DEBUG: CPythonInvoker(3, /home/evan/.local/share/xbmc/addons/script.artwork.downloader/service.py): instantiating addon using automatically obtained id of "script.artwork.downloader" dependent on version 2.1.0 of the xbmc.python api
15:36:19 T:140185377363904 DEBUG: ------ Window Init (Home.xml) ------
15:36:19 T:140185377363904 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
15:36:19 T:140184494528256 DEBUG: CPythonInvoker(2, /home/evan/.local/share/xbmc/addons/script.module.simple.downloader/default.py): instantiating addon using automatically obtained id of "script.module.simple.downloader" dependent on version 2.1.0 of the xbmc.python api
15:36:19 T:140184502920960 DEBUG: CPythonInvoker(1, /home/evan/.local/share/xbmc/addons/service.skin.widgets/default.py): setting the Python path to /home/evan/.local/share/xbmc/addons/service.skin.widgets:/home/evan/.local/share/xbmc/addons/script.module.simplejson/lib:/home/evan/.local/share/xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/evan/.local/share/xbmc/addons/script.common.plugin.cache/lib:/home/evan/.local/share/xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/evan/.local/share/xbmc/addons/script.module.parsedom/lib:/home/evan/.local/share/xbmc/addons/script.module.elementtree/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:36:19 T:140184502920960 DEBUG: CPythonInvoker(1, /home/evan/.local/share/xbmc/addons/service.skin.widgets/default.py): entering source directory /home/evan/.local/share/xbmc/addons/service.skin.widgets
15:36:19 T:140184502920960 DEBUG: CPythonInvoker(1, /home/evan/.local/share/xbmc/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:36:19 T:140184477742848 DEBUG: CPythonInvoker(4, /home/evan/.local/share/xbmc/addons/script.common.plugin.cache/default.py): setting the Python path to /home/evan/.local/share/xbmc/addons/script.common.plugin.cache:/home/evan/.local/share/xbmc/addons/script.module.simplejson/lib:/home/evan/.local/share/xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/evan/.local/share/xbmc/addons/script.common.plugin.cache/lib:/home/evan/.local/share/xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/evan/.local/share/xbmc/addons/script.module.parsedom/lib:/home/evan/.local/share/xbmc/addons/script.module.elementtree/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:36:19 T:140184477742848 DEBUG: CPythonInvoker(4, /home/evan/.local/share/xbmc/addons/script.common.plugin.cache/default.py): entering source directory /home/evan/.local/share/xbmc/addons/script.common.plugin.cache
15:36:19 T:140184511313664 NOTICE: -->Python Interpreter Initialized<--
15:36:19 T:140184511313664 DEBUG: CPythonInvoker(0, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): the source file to load is /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py
15:36:19 T:140184477742848 DEBUG: CPythonInvoker(4, /home/evan/.local/share/xbmc/addons/script.common.plugin.cache/default.py): instantiating addon using automatically obtained id of "script.common.plugin.cache" dependent on version 2.1.0 of the xbmc.python api
15:36:19 T:140185377363904 DEBUG: Load Home.xml: 50.17ms
15:36:19 T:140185377363904 DEBUG: Alloc resources: 53.08ms (51.35 ms skin load)
15:36:19 T:140184305731328 NOTICE: Thread JobWorker start, auto delete: true
15:36:19 T:140184305731328 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
15:36:19 T:140184305731328 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:36:19 T:140184305731328 DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
15:36:19 T:140184305731328 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
15:36:19 T:140185377363904 DEBUG: no profile autoexec.py (/home/evan/.local/share/xbmc/userdata/autoexec.py) found, skipping
15:36:19 T:140185377363904 DEBUG: NetworkMessage - Starting network services
15:36:19 T:140185377363904 NOTICE: Webserver: Starting...
15:36:19 T:140185377363904 NOTICE: WebServer: Started the webserver
15:36:19 T:140185377363904 INFO: CZeroconfAvahi::clientCallback: avahi server not available. But may become later...
15:36:19 T:140185377363904 NOTICE: ES: Starting event server
15:36:19 T:140185377363904 ERROR: JSONRPC Server: Failed to connect to sdpd
15:36:19 T:140185377363904 INFO: JSONRPC Server: Successfully initialized
15:36:19 T:140184305731328 DEBUG: RunQuery took 2 ms for 10 items query: select * from movieview ORDER BY dateAdded desc, idMovie desc LIMIT 10
15:36:19 T:140183907333888 NOTICE: Thread TCPServer start, auto delete: false
15:36:19 T:140184254990080 NOTICE: Thread EventServer start, auto delete: false
15:36:19 T:140184254990080 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
15:36:19 T:140184254990080 NOTICE: UDP: Listening on port 9777
15:36:19 T:140184494528256 INFO: CPythonInvoker(2, /home/evan/.local/share/xbmc/addons/script.module.simple.downloader/default.py): script successfully run
15:36:20 T:140184305731328 DEBUG: RunQuery took 78 ms for 10 items query: select * from episodeview ORDER BY dateAdded desc, idEpisode desc LIMIT 10
15:36:20 T:140184305731328 DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10
15:36:20 T:140183896807168 NOTICE: Thread JobWorker start, auto delete: true
15:36:20 T:140184305731328 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
15:36:20 T:140184477742848 NOTICE: StorageServer Module loaded RUN
15:36:20 T:140184477742848 NOTICE: StorageClient-2.5.2 Starting server
15:36:20 T:140184477742848 NOTICE: StorageServer-2.5.2 Storage Server starting /home/evan/.cache/xbmc/commoncache.db
15:36:20 T:140184511313664 DEBUG: CPythonInvoker(0, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck:/home/evan/.local/share/xbmc/addons/script.module.simplejson/lib:/home/evan/.local/share/xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/evan/.local/share/xbmc/addons/script.common.plugin.cache/lib:/home/evan/.local/share/xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/evan/.local/share/xbmc/addons/script.module.parsedom/lib:/home/evan/.local/share/xbmc/addons/script.module.elementtree/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:36:20 T:140184511313664 DEBUG: CPythonInvoker(0, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): entering source directory /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck
15:36:20 T:140184511313664 DEBUG: CPythonInvoker(0, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 2.1.0 of the xbmc.python api
15:36:20 T:140184494528256 INFO: Python script stopped
15:36:20 T:140184494528256 DEBUG: Thread LanguageInvoker 140184494528256 terminating
15:36:20 T:140184502920960 DEBUG: Skin Widgets: script version 0.0.27 started
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director"], "limits": {"end": 20}, "sort": {"method": "random" }, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 3 ms for 2 items query: select * from movieview WHERE ((movieview.playCount IS NULL OR movieview.playCount < 1))
15:36:20 T:140184486135552 DEBUG: POParser: loaded 100 strings from file /home/evan/.local/share/xbmc/addons/script.artwork.downloader/resources/language/English/strings.po
15:36:20 T:140184511313664 DEBUG: XBMC Version Check: Version 0.1.17 started
15:36:20 T:140184511313664 INFO: CPythonInvoker(0, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): script successfully run
15:36:20 T:140185377363904 DEBUG: started alarm with name: checkatboot
15:36:20 T:140184494528256 NOTICE: Thread AlarmClock start, auto delete: false
15:36:20 T:140185377363904 DEBUG: started alarm with name: checkwhilerunning
15:36:20 T:140184511313664 INFO: Python script stopped
15:36:20 T:140184511313664 DEBUG: Thread LanguageInvoker 140184511313664 terminating
15:36:20 T:140184486135552 NOTICE: Artwork Downloader: ## Service - Run at startup: False
15:36:20 T:140184486135552 NOTICE: Artwork Downloader: ## Service - Delayed startup: minutes
15:36:20 T:140184486135552 NOTICE: Artwork Downloader: ## Service - Run as service: False
15:36:20 T:140184486135552 NOTICE: Artwork Downloader: ## Service - Time: :00
15:36:20 T:140184486135552 INFO: CPythonInvoker(3, /home/evan/.local/share/xbmc/addons/script.artwork.downloader/service.py): script successfully run
15:36:20 T:140184486135552 INFO: Python script stopped
15:36:20 T:140184486135552 DEBUG: Thread LanguageInvoker 140184486135552 terminating
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": { "properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime"], "limits": {"end": 20}, "sort": {"method": "random" }, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 13 ms for 176 items query: select * from episodeview WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
15:36:20 T:140185377363904 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMusicVideos", "params": {"properties": ["title", "artist", "playcount", "year", "plot", "genre", "runtime", "fanart", "thumbnail", "file", "streamdetails", "resume"], "limits": {"end": 20}, "sort": {"method": "random"}}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideoview
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "AudioLibrary.GetAlbums", "params": {"properties": ["title", "description", "albumlabel", "theme", "mood", "style", "type", "artist", "genre", "year", "thumbnail", "fanart", "rating", "playcount"], "limits": {"end": 20}, "sort": {"method": "random"}}}
15:36:20 T:140184502920960 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strAlbum <> ''
15:36:20 T:140184502920960 DEBUG: GetAlbumsByWhere - query took 0 ms
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "AudioLibrary.GetArtists", "params": {"properties": ["genre", "description", "mood", "style", "born", "died", "formed", "disbanded", "yearsactive", "instrument", "fanart", "thumbnail"], "sort": {"method": "random"}, "limits": {"end": 20}}, "id": 1}
15:36:20 T:140184502920960 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:36:20 T:140184502920960 DEBUG: Time to retrieve artists from dataset = 0
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "AudioLibrary.GetSongs", "params": {"properties": ["title", "playcount", "artist", "album", "year", "file", "thumbnail", "fanart", "rating"], "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}, "limits": {"end": 20}, "sort": {"method": "random"}}}
15:36:20 T:140184502920960 DEBUG: GetSongsByWhere query = SELECT songview.* FROM songview WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Addons.GetAddons", "params": {"properties": ["name", "author", "summary", "version", "fanart", "thumbnail"]}, "id": 1}
15:36:20 T:140184502920960 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:00.285925
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 0 ms for 1 items query: select * from movieview WHERE (movieview.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetTVShows", "params": {"properties": ["title", "studio", "mpaa", "file", "art"], "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}, "limits": {"end": 20}}, "id": 1}
15:36:20 T:140184502920960 DEBUG: RunQuery took 17 ms for 5 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:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 69, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
15:36:20 T:140184502920960 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=69) order by idShow
15:36:20 T:140184502920960 DEBUG: RunQuery took 1 ms for 14 items query: select * from episodeview WHERE (episodeview.idShow = 69) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
15:36:20 T:140184502920960 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 105, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
15:36:20 T:140184502920960 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=105) order by idShow
15:36:20 T:140184502920960 DEBUG: RunQuery took 0 ms for 10 items query: select * from episodeview WHERE (episodeview.idShow = 105) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
15:36:20 T:140184502920960 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 78, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
15:36:20 T:140184502920960 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=78) order by idShow
15:36:20 T:140184502920960 DEBUG: RunQuery took 1 ms for 25 items query: select * from episodeview WHERE (episodeview.idShow = 78) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
15:36:20 T:140184502920960 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 83, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
15:36:20 T:140184502920960 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=83) order by idShow
15:36:20 T:140184502920960 DEBUG: RunQuery took 1 ms for 8 items query: select * from episodeview WHERE (episodeview.idShow = 83) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
15:36:20 T:140184502920960 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 66, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
15:36:20 T:140184502920960 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=66) order by idShow
15:36:20 T:140184502920960 DEBUG: RunQuery took 1 ms for 13 items query: select * from episodeview WHERE (episodeview.idShow = 66) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
15:36:20 T:140184502920960 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "AudioLibrary.GetAlbums", "params": {"properties": ["title", "description", "albumlabel", "theme", "mood", "style", "type", "artist", "genre", "year", "thumbnail", "fanart", "rating", "playcount"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "playcount" }}}
15:36:20 T:140184502920960 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strAlbum <> ''
15:36:20 T:140184502920960 DEBUG: GetAlbumsByWhere - query took 1 ms
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMusicVideos", "params": {"properties": ["title", "artist", "playcount", "year", "plot", "genre", "runtime", "fanart", "thumbnail", "file", "streamdetails", "resume"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "playcount" }}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideoview
15:36:20 T:140184502920960 DEBUG: Skin Widgets: Total time needed to request recommended queries: 0:00:00.110563
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "dateadded"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 1 ms for 2 items query: select * from movieview WHERE ((movieview.playCount IS NULL OR movieview.playCount = 0))
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": { "properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "dateadded"}, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 11 ms for 176 items query: select * from episodeview WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMusicVideos", "params": {"properties": ["title", "artist", "playcount", "year", "plot", "genre", "runtime", "fanart", "thumbnail", "file", "streamdetails", "resume"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "dateadded"}}}
15:36:20 T:140184502920960 DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideoview
15:36:20 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "AudioLibrary.GetAlbums", "params": {"properties": ["title", "description", "albumlabel", "theme", "mood", "style", "type", "artist", "genre", "year", "thumbnail", "fanart", "rating", "playcount"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "dateadded" }}}
15:36:20 T:140184502920960 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strAlbum <> ''
15:36:20 T:140184502920960 DEBUG: GetAlbumsByWhere - query took 0 ms
15:36:20 T:140184502920960 DEBUG: Skin Widgets: Total time needed to request recent items queries: 0:00:00.109312
15:36:20 T:140184502920960 DEBUG: Skin Widgets: Total time needed for all queries: 0:00:00.506164
15:36:31 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:31 T:140184971536128 INFO: CActiveAESink::OpenSink - initialize sink
15:36:31 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
15:36:31 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:36:31 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:36:31 T:140184971536128 DEBUG: PulseAudio: Context ready
15:36:31 T:140184486135552 NOTICE: Thread JobWorker start, auto delete: true
15:36:31 T:140184971536128 DEBUG: PulseAudio: Stream ready
15:36:31 T:140184971536128 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
15:36:31 T:140184971536128 DEBUG: Output Device : Default
15:36:31 T:140184971536128 DEBUG: Sample Rate : 44100
15:36:31 T:140184971536128 DEBUG: Sample Format : AE_FMT_FLOAT
15:36:31 T:140184971536128 DEBUG: Channel Count : 6
15:36:31 T:140184971536128 DEBUG: Channel Layout: FL,FR,FC,BL,BR,LFE
15:36:31 T:140184971536128 DEBUG: Frames : 500
15:36:31 T:140184971536128 DEBUG: Frame Samples : 3000
15:36:31 T:140184971536128 DEBUG: Frame Size : 24
15:36:31 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:31 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:32 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:32 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:32 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:32 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:32 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:33 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:33 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:34 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:34 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:35 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:35 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:36 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:36 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:36 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:36 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:36 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:36 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:37 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:37 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:38 T:140185377363904 DEBUG: Joystick 0 button 1 Down
15:36:38 T:140185377363904 DEBUG: ExecuteXBMCAction : Translating action string
15:36:38 T:140185377363904 DEBUG: Activating window ID: 10025
15:36:38 T:140185377363904 DEBUG: ------ Window Deinit (Home.xml) ------
15:36:38 T:140185377363904 DEBUG: ExecuteXBMCAction : Translating action string
15:36:38 T:140185377363904 DEBUG: ------ Window Init (MyVideoNav.xml) ------
15:36:38 T:140185377363904 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
15:36:38 T:140185377363904 DEBUG: Load MyVideoNav.xml: 59.77ms
15:36:38 T:140185377363904 DEBUG: Alloc resources: 63.43ms (62.19 ms skin load)
15:36:38 T:140185377363904 DEBUG: CGUIMediaWindow::GetDirectory (videodb://movies/titles/)
15:36:38 T:140185377363904 DEBUG: ParentPath = [videodb://movies/titles/]
15:36:38 T:140185377363904 DEBUG: RunQuery took 1 ms for 13 items query: select * from movieview
15:36:38 T:140184511313664 NOTICE: Thread BackgroundLoader start, auto delete: false
15:36:38 T:140184511313664 DEBUG: Thread BackgroundLoader 140184511313664 terminating
15:36:38 T:140184511313664 NOTICE: Thread BackgroundLoader start, auto delete: false
15:36:38 T:140184511313664 DEBUG: Thread BackgroundLoader 140184511313664 terminating
15:36:38 T:140185377363904 DEBUG: Joystick 0 button 1 Up
15:36:39 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:39 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:40 T:140185377363904 DEBUG: Joystick 0 button 1 Down
15:36:40 T:140185377363904 INFO: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY
15:36:40 T:140185377363904 DEBUG: Load DialogContextMenu.xml: 2.05ms
15:36:40 T:140185377363904 DEBUG: Alloc resources: 2.39ms (2.11 ms skin load)
15:36:40 T:140185377363904 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
15:36:40 T:140185377363904 DEBUG: Window DialogContextMenu.xml was already loaded
15:36:40 T:140185377363904 DEBUG: Alloc resources: 0.02m
15:36:40 T:140185377363904 DEBUG: Joystick 0 button 1 Up
15:36:41 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:36:41 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:36:41 T:140185377363904 DEBUG: Joystick 0 button 1 Down
15:36:41 T:140185377363904 DEBUG: Joystick 0 button 1 Up
15:36:41 T:140185377363904 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
15:36:42 T:140185377363904 DEBUG: SECTION:LoadDLL(libnfs.so.1)
15:36:42 T:140185377363904 DEBUG: Loading: libnfs.so.1
15:36:42 T:140185377363904 DEBUG: NFS: Context for desktop/home/evan/documents/multimedia not open - get a new context.
15:36:42 T:140185377363904 DEBUG: NFS: Connected to server desktop and export /home/evan/documents/multimedia
15:36:42 T:140185377363904 DEBUG: NFS: chunks: r/w 1048576/1048576
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:36:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:36:42 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers(nfs://desktop/home/evan/documents/multimedia/videos/movies/Wolf Children.mkv)
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
15:36:42 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
15:36:42 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
15:36:42 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
15:36:42 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
15:36:42 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
15:36:42 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
15:36:42 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
15:36:42 T:140185377363904 NOTICE: DVDPlayer: Opening: nfs://desktop/home/evan/documents/multimedia/videos/movies/Wolf Children.mkv
15:36:42 T:140185377363904 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
15:36:42 T:140185377363904 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
15:36:42 T:140185377363904 DEBUG: LinuxRendererGL: Cleaning up GL resources
15:36:42 T:140185377363904 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
15:36:42 T:140185377363904 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-2-x86_64-linux.so)
15:36:42 T:140185377363904 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swscale-2-x86_64-linux.so
15:36:42 T:140184511313664 NOTICE: Thread DVDPlayer start, auto delete: false
15:36:42 T:140184511313664 NOTICE: Creating InputStream
15:36:42 T:140184511313664 DEBUG: CNFSFile::Open - opened home/evan/documents/multimedia/videos/movies/Wolf Children.mkv
15:36:42 T:140184511313664 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
15:36:42 T:140184511313664 DEBUG: ScanForExternalSubtitles: END (total time: 37 ms)
15:36:42 T:140184511313664 NOTICE: Creating Demuxer
15:36:42 T:140184511313664 DEBUG: Open - probing detected format [matroska,webm]
15:36:42 T:140184511313664 DEBUG: Open - avformat_find_stream_info starting
15:36:42 T:140184511313664 DEBUG: ffmpeg[3FFFF700]: [matroska,webm] Stream #3: not enough frames to estimate rate; consider increasing probesize
15:36:42 T:140184511313664 DEBUG: ffmpeg[3FFFF700]: [matroska,webm] Stream #4: not enough frames to estimate rate; consider increasing probesize
15:36:42 T:140184511313664 DEBUG: ffmpeg[3FFFF700]: [matroska,webm] Could not find codec parameters for stream 3 (Subtitle: hdmv_pgs_subtitle): unspecified size
15:36:42 T:140184511313664 DEBUG: ffmpeg[3FFFF700]: [matroska,webm] Consider increasing the value for the 'analyzeduration' and 'probesize' options
15:36:42 T:140184511313664 DEBUG: ffmpeg[3FFFF700]: [matroska,webm] Could not find codec parameters for stream 4 (Subtitle: hdmv_pgs_subtitle): unspecified size
15:36:42 T:140184511313664 DEBUG: ffmpeg[3FFFF700]: [matroska,webm] Consider increasing the value for the 'analyzeduration' and 'probesize' options
15:36:42 T:140184511313664 DEBUG: Open - av_find_stream_info finished
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Input #0, matroska,webm, from 'nfs://desktop/home/evan/documents/multimedia/videos/movies/Wolf Children.mkv':
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Metadata:
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: title : Wolf Children
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: creation_time : 2013-11-24 08:05:21
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Duration: 01:57:03.04, start: 0.000000, bitrate: 8530 kb/s
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Stream #0:0(eng): Video: h264 (High), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 180k tbc (default)
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Stream #0:1(eng): Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s (default)
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Metadata:
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: title : English 5.1
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Stream #0:2(jpn): Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Metadata:
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: title : Japanese 5.1
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Stream #0:3(eng): Subtitle: hdmv_pgs_subtitle (default)
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Metadata:
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: title : English Signs/Lyrics
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Stream #0:4(eng): Subtitle: hdmv_pgs_subtitle
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: Metadata:
15:36:42 T:140184511313664 INFO: ffmpeg[3FFFF700]: title : English Subtitles
15:36:42 T:140184511313664 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
15:36:42 T:140184511313664 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
15:36:42 T:140184511313664 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
15:36:42 T:140184511313664 DEBUG: CDVDDemuxFFmpeg::AddStream(3, ...) -> 3
15:36:42 T:140184511313664 DEBUG: CDVDDemuxFFmpeg::AddStream(4, ...) -> 4
15:36:42 T:140184511313664 NOTICE: Opening video stream: 0 source: 256
15:36:42 T:140184511313664 NOTICE: Creating video codec with codec id: 28
15:36:42 T:140184511313664 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes
15:36:42 T:140184511313664 DEBUG: FactoryCodec - Video: - Opening
15:36:42 T:140184511313664 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/postproc-52-x86_64-linux.so)
15:36:42 T:140184511313664 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/postproc-52-x86_64-linux.so
15:36:42 T:140184511313664 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avfilter-3-x86_64-linux.so)
15:36:42 T:140184511313664 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avfilter-3-x86_64-linux.so
15:36:42 T:140184511313664 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
15:36:42 T:140184511313664 DEBUG: CDVDVideoCodecFFmpeg::Open() Keep default threading 3 by videoplayer.useframemtdec
15:36:42 T:140184511313664 DEBUG: FactoryCodec - Video: ff-h264 - Opened
15:36:42 T:140183638898432 NOTICE: Thread VideoReferenceClock start, auto delete: false
15:36:42 T:140183638898432 DEBUG: CVideoReferenceClock: Setting up GLX
15:36:43 T:140183638898432 DEBUG: CVideoReferenceClock: GL_VENDOR:x.org, not using nvidia-settings
15:36:43 T:140183638898432 DEBUG: CVideoReferenceClock: Using RandR for refreshrate detection
15:36:43 T:140183638898432 DEBUG: CVideoReferenceClock: Detected refreshrate: 60 hertz
15:36:43 T:140184511313664 NOTICE: Creating video thread
15:36:43 T:140183630505728 NOTICE: Thread DVDPlayerVideo start, auto delete: false
15:36:43 T:140183630505728 NOTICE: running thread: video_thread
15:36:43 T:140184511313664 NOTICE: Opening audio stream: 1 source: 256
15:36:43 T:140184511313664 NOTICE: Finding audio codec for: 86019
15:36:43 T:140183630505728 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
15:36:43 T:140184511313664 DEBUG: FactoryCodec - Audio: passthrough - Opening
15:36:43 T:140184511313664 DEBUG: FactoryCodec - Audio: passthrough - Opened
15:36:43 T:140184511313664 NOTICE: Creating audio thread
15:36:43 T:140183622113024 NOTICE: Thread DVDPlayerAudio start, auto delete: false
15:36:43 T:140183622113024 NOTICE: running thread: CDVDPlayerAudio::Process()
15:36:43 T:140184511313664 NOTICE: Opening Subtitle stream: 3 source: 256
15:36:43 T:140184511313664 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening
15:36:43 T:140184511313664 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened
15:36:43 T:140184511313664 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: nfs://desktop/home/evan/documents/multimedia/videos/movies/Wolf Children.mkv
15:36:43 T:140184511313664 DEBUG: OnPlayBackStarted : play state was 1, starting 1
15:36:43 T:140184511313664 DEBUG: CDVDPlayer::SetCaching - caching state 3
15:36:43 T:140185377363904 DEBUG: PlayFile : OpenFile succeed, play state 2
15:36:43 T:140185377363904 DEBUG: OnPlayBackStarted : play state was 2, starting 0
15:36:43 T:140183622113024 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (6 channels, 48000Hz)
15:36:43 T:140183622113024 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)
15:36:43 T:140183630505728 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
15:36:43 T:140184971536128 INFO: CActiveAESink::OpenSink - initialize sink
15:36:43 T:140183630505728 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(166000.000000, 0)
15:36:43 T:140183622113024 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:alsa_output.pci-0000_00_01.1.hdmi-stereo
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context ready
15:36:43 T:140184971536128 DEBUG: PulseAudio: Stream failed
15:36:43 T:140184971536128 ERROR: PulseAudio: Waited for the stream but it failed
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:36:43 T:140184971536128 DEBUG: PulseAudio: Context ready
15:36:43 T:140184971536128 DEBUG: PulseAudio: Stream failed
15:36:43 T:140184971536128 ERROR: PulseAudio: Waited for the stream but it failed
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - open NULL sink
15:36:43 T:140184963143424 NOTICE: Thread AESinkNull start, auto delete: false
15:36:43 T:140184963143424 DEBUG: CAESinkNULL::Process
15:36:43 T:140184971536128 DEBUG: CActiveAESink::OpenSink - NULL Initialized:
15:36:43 T:140184971536128 DEBUG: Output Device : Default
15:36:43 T:140184971536128 DEBUG: Sample Rate : 48000
15:36:43 T:140184971536128 DEBUG: Sample Format : AE_FMT_S16NE
15:36:43 T:140184971536128 DEBUG: Channel Count : 2
15:36:43 T:140184971536128 DEBUG: Channel Layout: RAW,RAW
15:36:43 T:140184971536128 DEBUG: Frames : 12000
15:36:43 T:140184971536128 DEBUG: Frame Samples : 2
15:36:43 T:140184971536128 DEBUG: Frame Size : 4
15:36:43 T:140184923256576 WARNING: ActiveAE::Configure - sink returned large buffer of 250 ms, reducing to 80 ms
15:36:43 T:140184923256576 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:36:43 T:140184511313664 DEBUG: Previous line repeats 1 times.
15:36:43 T:140184511313664 DEBUG: CDVDPlayer::HandleMessages - player started 1
15:36:43 T:140185377363904 DEBUG: CGUIInfoManager::SetCurrentMovie(nfs://desktop/home/evan/documents/multimedia/videos/movies/Wolf Children.mkv)
15:36:43 T:140185377363904 DEBUG: GetMovieId (nfs://desktop/home/evan/documents/multimedia/videos/movies/Wolf Children.mkv), query = select idMovie from movie where idFile=3164
15:36:43 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
15:36:43 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
15:36:43 T:140183630505728 NOTICE: fps: 23.976024, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
15:36:43 T:140183630505728 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 23.98. format: YV12
15:36:43 T:140183630505728 NOTICE: Display resolution ADJUST : HDMI-0: 1920x1080 @ 23.98Hz (22) (weight: 0.000)
15:36:43 T:140183630505728 DEBUG: CXBMCRenderManager::Configure - 3
15:36:43 T:140183630505728 DEBUG: CVideoReferenceClock: Clock speed 100.100000%
15:36:43 T:140185377363904 DEBUG: Activating window ID: 12005
15:36:43 T:140185377363904 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
15:36:43 T:140185377363904 DEBUG: OnLostDevice - notify display change event
15:36:43 T:140185377363904 DEBUG: Flush - flushing renderer
15:36:43 T:140185377363904 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI-0 --mode 0x5c
15:36:43 T:140184511313664 DEBUG: CDVDPlayer::SetCaching - caching state 0
15:36:43 T:140183622113024 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1)
15:36:43 T:140183622113024 DEBUG: CDVDPlayerAudio:: synctype set to 1: skip/duplicate
15:36:43 T:140183622113024 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:-330858.093484, should be:-330854.667484, error:3.426000
15:36:43 T:140183638898432 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 2, refreshrate might have changed
15:36:43 T:140185377363904 ERROR: GLX: Same window as before, refreshing context
15:36:43 T:140185377363904 INFO: GL: Maximum texture width: 16384
15:36:43 T:140185377363904 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
15:36:43 T:140185377363904 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
15:36:43 T:140185377363904 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
15:36:43 T:140185377363904 DEBUG: Load VideoFullScreen.xml: 14.19ms
15:36:43 T:140185377363904 DEBUG: Alloc resources: 15.06ms (14.34 ms skin load)
15:36:43 T:140183638898432 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 2, refreshrate might have changed
15:36:43 T:140185377363904 DEBUG: Previous line repeats 1 times.
15:36:43 T:140185377363904 NOTICE: Using GL_TEXTURE_2D
15:36:43 T:140185377363904 DEBUG: GL: Requested render method: 0
15:36:43 T:140185377363904 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_YV12
15:36:43 T:140185377363904 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
15:36:43 T:140185377363904 DEBUG: GL: Vertex Shader compilation log:
15:36:43 T:140185377363904 DEBUG: GL: Vertex Shader compiled successfully
15:36:43 T:140185377363904 DEBUG: GL: Pixel Shader compilation log:
15:36:43 T:140185377363904 DEBUG: GL: Fragment Shader compiled successfully
15:36:43 T:140185377363904 DEBUG: GL: Vertex Shader compilation log:
15:36:43 T:140185377363904 DEBUG: GL: Vertex Shader compiled successfully
15:36:43 T:140185377363904 DEBUG: GL: Pixel Shader compilation log:
15:36:43 T:140185377363904 DEBUG: GL: Fragment Shader compiled successfully
15:36:43 T:140185377363904 NOTICE: GL: NPOT texture support detected
15:36:43 T:140185377363904 NOTICE: GL: Using GL_ARB_pixel_buffer_object
15:36:43 T:140185377363904 DEBUG: CheckDisplayEvents: Received RandR event 89
15:36:43 T:140185377363904 DEBUG: CheckDisplayEvents - notify display reset event
15:36:43 T:140183638898432 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 2, refreshrate might have changed
15:36:43 T:140184511313664 DEBUG: CDVDPlayer::HandleMessages - player started 2
15:36:43 T:140183638898432 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 2, refreshrate might have changed
15:36:44 T:140183638898432 DEBUG: Previous line repeats 20 times.
15:36:44 T:140183638898432 DEBUG: CVideoReferenceClock: Received RandR event 89
15:36:44 T:140183638898432 DEBUG: CVideoReferenceClock: Detected refreshrate: 24 hertz
15:36:45 T:140183622113024 DEBUG: CDVDPlayerAudio:: Dropping packet of 32 ms
15:36:46 T:140185377363904 DEBUG: ------ Window Init (DialogVolumeBar.xml) ------
15:36:46 T:140185377363904 DEBUG: Window DialogVolumeBar.xml was already loaded
15:36:46 T:140185377363904 DEBUG: Alloc resources: 0.02m
15:36:47 T:140183622113024 DEBUG: CDVDPlayerAudio:: Duplicating packet of 32 ms
15:36:47 T:140183622113024 DEBUG: Previous line repeats 1 times.
15:36:47 T:140183622113024 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:4010339.637516, should be:4129447.610880, error:119107.973364
15:36:48 T:140185377363904 DEBUG: ------ Window Deinit (DialogVolumeBar.xml) ------
15:36:48 T:140183622113024 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:4756151.369880, should be:4623199.103151, error:-132952.266729
15:36:48 T:140183630505728 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
15:36:49 T:140185377363904 DEBUG: ------ Window Init (DialogVolumeBar.xml) ------
15:36:49 T:140185377363904 DEBUG: Window DialogVolumeBar.xml was already loaded
15:36:49 T:140185377363904 DEBUG: Alloc resources: 0.07m
15:36:50 T:140184703223552 NOTICE: Thread LanguageInvoker start, auto delete: false
15:36:50 T:140184703223552 INFO: initializing python engine.
15:36:50 T:140184703223552 DEBUG: CPythonInvoker(5, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): start processing
15:36:50 T:140184703223552 NOTICE: -->Python Interpreter Initialized<--
15:36:50 T:140184703223552 DEBUG: CPythonInvoker(5, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): the source file to load is /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py
15:36:50 T:140184703223552 DEBUG: CPythonInvoker(5, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck:/home/evan/.local/share/xbmc/addons/script.module.simplejson/lib:/home/evan/.local/share/xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/evan/.local/share/xbmc/addons/script.common.plugin.cache/lib:/home/evan/.local/share/xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/evan/.local/share/xbmc/addons/script.module.parsedom/lib:/home/evan/.local/share/xbmc/addons/script.module.elementtree/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:36:50 T:140184703223552 DEBUG: CPythonInvoker(5, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): entering source directory /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck
15:36:50 T:140184703223552 DEBUG: CPythonInvoker(5, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 2.1.0 of the xbmc.python api
15:36:50 T:140184703223552 DEBUG: XBMC Version Check: Version 0.1.17 started
15:36:50 T:140184703223552 DEBUG: JSONRPC: Incoming request: { "jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["version", "name"]}, "id": 1 }
15:36:50 T:140184703223552 DEBUG: XBMC Version Check: Version installed {u'major': 13, u'tag': u'prealpha', u'minor': 0, u'revision': u'20140115-63e652f'}
15:36:50 T:140184703223552 DEBUG: XBMC Version Check: Nothing to see here, move along. Running a latest non stable release
15:36:50 T:140184703223552 INFO: CPythonInvoker(5, /home/evan/.local/share/xbmc/addons/service.xbmc.versioncheck/service.py): script successfully run
15:36:50 T:140184703223552 INFO: Python script stopped
15:36:50 T:140184703223552 DEBUG: Thread LanguageInvoker 140184703223552 terminating
15:36:51 T:140185377363904 DEBUG: ------ Window Deinit (DialogVolumeBar.xml) ------
15:36:51 T:140185377363904 DEBUG: Joystick 0 button 2 Down
15:36:51 T:140185377363904 NOTICE: CDVDPlayer::CloseFile()
15:36:51 T:140185377363904 NOTICE: DVDPlayer: waiting for threads to exit
15:36:51 T:140184511313664 NOTICE: CDVDPlayer::OnExit()
15:36:51 T:140184511313664 NOTICE: DVDPlayer: closing audio stream
15:36:51 T:140184511313664 NOTICE: Closing audio stream
15:36:51 T:140184511313664 NOTICE: Waiting for audio thread to exit
15:36:51 T:140183622113024 NOTICE: thread end: CDVDPlayerAudio::OnExit()
15:36:51 T:140183622113024 DEBUG: Thread DVDPlayerAudio 140183622113024 terminating
15:36:51 T:140184511313664 NOTICE: Closing audio device
15:36:52 T:140184923256576 DEBUG: CActiveAE::DiscardStream - audio stream deleted
15:36:52 T:140184923256576 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:36:52 T:140184511313664 NOTICE: Deleting audio codec
15:36:52 T:140184511313664 NOTICE: DVDPlayer: closing video stream
15:36:52 T:140184511313664 NOTICE: Closing video stream
15:36:52 T:140184511313664 NOTICE: waiting for video thread to exit
15:36:52 T:140184923256576 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:36:52 T:140184971536128 INFO: CActiveAESink::OpenSink - initialize sink
15:36:52 T:140183630505728 NOTICE: thread end: video_thread
15:36:52 T:140183630505728 DEBUG: Thread DVDPlayerVideo 140183630505728 terminating
15:36:52 T:140184511313664 NOTICE: deleting video codec
15:36:52 T:140184511313664 NOTICE: DVDPlayer: closing subtitle stream
15:36:52 T:140184511313664 NOTICE: Closing subtitle stream
15:36:52 T:140184511313664 NOTICE: CDVDPlayer::OnExit() deleting demuxer
15:36:52 T:140184511313664 NOTICE: CDVDPlayer::OnExit() deleting input stream
15:36:52 T:140184511313664 DEBUG: CNFSFile::Close closing file home/evan/documents/multimedia/videos/movies/Wolf Children.mkv
15:36:52 T:140184511313664 DEBUG: OnPlayBackStopped : play state was 2, starting 0
15:36:52 T:140184511313664 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
15:36:52 T:140184511313664 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
15:36:52 T:140184511313664 DEBUG: Thread DVDPlayer 140184511313664 terminating
15:36:52 T:140185377363904 NOTICE: DVDPlayer: finished waiting
15:36:52 T:140185377363904 DEBUG: LinuxRendererGL: Cleaning up GL resources
15:36:52 T:140185377363904 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
15:36:52 T:140185377363904 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
15:36:52 T:140185377363904 DEBUG: OnLostDevice - notify display change event
15:36:52 T:140185377363904 DEBUG: Flush - flushing renderer
15:36:52 T:140184963143424 DEBUG: Thread AESinkNull 140184963143424 terminating
15:36:52 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
15:36:52 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:36:52 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:36:52 T:140184971536128 DEBUG: PulseAudio: Context ready
15:36:52 T:140184971536128 DEBUG: PulseAudio: Stream ready
15:36:52 T:140184971536128 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
15:36:52 T:140184971536128 DEBUG: Output Device : Default
15:36:52 T:140184971536128 DEBUG: Sample Rate : 44100
15:36:52 T:140184971536128 DEBUG: Sample Format : AE_FMT_FLOAT
15:36:52 T:140184971536128 DEBUG: Channel Count : 6
15:36:52 T:140184971536128 DEBUG: Channel Layout: FL,FR,FC,BL,BR,LFE
15:36:52 T:140184971536128 DEBUG: Frames : 500
15:36:52 T:140184971536128 DEBUG: Frame Samples : 3000
15:36:52 T:140184971536128 DEBUG: Frame Size : 24
15:36:52 T:140184923256576 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:36:52 T:140185377363904 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI-0 --mode 0x54
15:36:52 T:140185377363904 ERROR: GLX: Same window as before, refreshing context
15:36:52 T:140185377363904 INFO: GL: Maximum texture width: 16384
15:36:52 T:140185377363904 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
15:36:52 T:140185377363904 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
15:36:52 T:140185377363904 DEBUG: ------ Window Init (MyVideoNav.xml) ------
15:36:52 T:140185377363904 DEBUG: Window MyVideoNav.xml was already loaded
15:36:52 T:140185377363904 DEBUG: Alloc resources: 1.62m
15:36:52 T:140185377363904 DEBUG: CGUIMediaWindow::GetDirectory (videodb://movies/titles/)
15:36:52 T:140185377363904 DEBUG: ParentPath = [videodb://movies/titles/]
15:36:52 T:140185377363904 DEBUG: RunQuery took 0 ms for 13 items query: select * from movieview
15:36:52 T:140184511313664 NOTICE: Thread BackgroundLoader start, auto delete: false
15:36:52 T:140184511313664 DEBUG: Thread BackgroundLoader 140184511313664 terminating
15:36:52 T:140185377363904 DEBUG: CheckDisplayEvents: Received RandR event 89
15:36:52 T:140185377363904 DEBUG: CheckDisplayEvents - notify display reset event
15:36:52 T:140185377363904 NOTICE: CDVDPlayer::CloseFile()
15:36:52 T:140185377363904 NOTICE: DVDPlayer: waiting for threads to exit
15:36:52 T:140185377363904 NOTICE: DVDPlayer: finished waiting
15:36:52 T:140185377363904 DEBUG: LinuxRendererGL: Cleaning up GL resources
15:36:52 T:140185377363904 NOTICE: CDVDPlayer::CloseFile()
15:36:52 T:140185377363904 NOTICE: DVDPlayer: waiting for threads to exit
15:36:52 T:140185377363904 NOTICE: DVDPlayer: finished waiting
15:36:52 T:140185377363904 DEBUG: LinuxRendererGL: Cleaning up GL resources
15:36:52 T:140183638898432 DEBUG: CVideoReferenceClock: Cleaning up GLX
15:36:52 T:140183638898432 DEBUG: Thread VideoReferenceClock 140183638898432 terminating
15:36:52 T:140185377363904 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)
15:36:52 T:140185377363904 DEBUG: Unloading: ImageLib-x86_64-linux.so
15:36:52 T:140184305731328 DEBUG: DoWork - Saving file state for video item nfs://desktop/home/evan/documents/multimedia/videos/movies/Wolf Children.mkv
15:36:52 T:140185377363904 DEBUG: Joystick 0 button 2 Up
15:36:52 T:140184305731328 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
15:36:52 T:140184305731328 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
15:36:53 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}}}
15:36:53 T:140184502920960 DEBUG: RunQuery took 1 ms for 0 items query: select * from movieview WHERE (movieview.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
15:36:53 T:140184502920960 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "dateadded"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}}}
15:36:53 T:140184502920960 DEBUG: RunQuery took 1 ms for 2 items query: select * from movieview WHERE ((movieview.playCount IS NULL OR movieview.playCount = 0))
15:37:22 T:140185377363904 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/postproc-52-x86_64-linux.so)
15:37:22 T:140185377363904 DEBUG: Unloading: postproc-52-x86_64-linux.so
15:37:22 T:140183896807168 DEBUG: Thread JobWorker 140183896807168 terminating (autodelete)
15:37:22 T:140184486135552 DEBUG: Thread JobWorker 140184486135552 terminating (autodelete)
15:37:22 T:140184305731328 DEBUG: Thread JobWorker 140184305731328 terminating (autodelete)
15:37:22 T:140185377363904 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avfilter-3-x86_64-linux.so)
15:37:22 T:140185377363904 DEBUG: Unloading: avfilter-3-x86_64-linux.so
15:37:57 T:140185377363904 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/swresample-0-x86_64-linux.so)
15:37:57 T:140185377363904 DEBUG: Unloading: swresample-0-x86_64-linux.so
15:38:23 T:140185377363904 NOTICE: NFS is idle. Closing the remaining connections.
15:38:53 T:140185377363904 DEBUG: SECTION:UnloadDelayed(DLL: libnfs.so.1)
15:38:53 T:140185377363904 DEBUG: Unloading: libnfs.so.1
15:39:40 T:140185377363904 DEBUG: Joystick 0 hat 1 Down
15:39:40 T:140184971536128 INFO: CActiveAESink::OpenSink - initialize sink
15:39:40 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
15:39:40 T:140184305731328 NOTICE: Thread JobWorker start, auto delete: true
15:39:40 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:39:40 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:39:40 T:140184971536128 DEBUG: PulseAudio: Context ready
15:39:40 T:140184971536128 DEBUG: PulseAudio: Stream ready
15:39:40 T:140184971536128 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
15:39:40 T:140184971536128 DEBUG: Output Device : Default
15:39:40 T:140184971536128 DEBUG: Sample Rate : 44100
15:39:40 T:140184971536128 DEBUG: Sample Format : AE_FMT_FLOAT
15:39:40 T:140184971536128 DEBUG: Channel Count : 6
15:39:40 T:140184971536128 DEBUG: Channel Layout: FL,FR,FC,BL,BR,LFE
15:39:40 T:140184971536128 DEBUG: Frames : 500
15:39:40 T:140184971536128 DEBUG: Frame Samples : 3000
15:39:40 T:140184971536128 DEBUG: Frame Size : 24
15:39:40 T:140185377363904 DEBUG: Joystick 0 hat 4294967295 Centered
15:39:40 T:140185377363904 DEBUG: Joystick 0 button 1 Down
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
15:39:40 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
15:39:40 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers(nfs://desktop/home/evan/documents/multimedia/videos/movies/This is Spinal Tap.avi)
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
15:39:40 T:140185377363904 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
15:39:40 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
15:39:40 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
15:39:40 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
15:39:40 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
15:39:40 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
15:39:40 T:140185377363904 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
15:39:40 T:140185377363904 NOTICE: DVDPlayer: Opening: nfs://desktop/home/evan/documents/multimedia/videos/movies/This is Spinal Tap.avi
15:39:40 T:140185377363904 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
15:39:40 T:140185377363904 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
15:39:40 T:140185377363904 DEBUG: LinuxRendererGL: Cleaning up GL resources
15:39:40 T:140185377363904 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
15:39:40 T:140183896807168 NOTICE: Thread DVDPlayer start, auto delete: false
15:39:40 T:140183896807168 NOTICE: Creating InputStream
15:39:40 T:140183896807168 DEBUG: SECTION:LoadDLL(libnfs.so.1)
15:39:40 T:140183896807168 DEBUG: Loading: libnfs.so.1
15:39:40 T:140183896807168 DEBUG: NFS: Context for desktop/home/evan/documents/multimedia not open - get a new context.
15:39:40 T:140183896807168 DEBUG: NFS: Connected to server desktop and export /home/evan/documents/multimedia
15:39:40 T:140183896807168 DEBUG: NFS: chunks: r/w 1048576/1048576
15:39:40 T:140183896807168 DEBUG: CNFSFile::Open - opened home/evan/documents/multimedia/videos/movies/This is Spinal Tap.avi
15:39:40 T:140183896807168 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
15:39:40 T:140183896807168 DEBUG: ScanForExternalSubtitles: END (total time: 4 ms)
15:39:40 T:140183896807168 NOTICE: Creating Demuxer
15:39:41 T:140185377363904 DEBUG: ------ Window Init (DialogBusy.xml) ------
15:39:41 T:140185377363904 DEBUG: Window DialogBusy.xml was already loaded
15:39:41 T:140185377363904 DEBUG: Alloc resources: 0.01m
15:39:41 T:140185377363904 DEBUG: Joystick 0 button 1 Up
15:39:41 T:140183896807168 DEBUG: Open - probing detected format [avi]
15:39:41 T:140183896807168 DEBUG: Open - avformat_find_stream_info starting
15:39:41 T:140183896807168 DEBUG: Open - av_find_stream_info finished
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: Input #0, avi, from 'nfs://desktop/home/evan/documents/multimedia/videos/movies/This is Spinal Tap.avi':
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: Duration: 01:22:46.96, start: 0.000000, bitrate: 1182 kb/s
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (DX50 / 0x30355844), yuv420p, 640x352 [SAR 1:1 DAR 20:11], 23.98 tbr, 23.98 tbn, 23.98 tbc
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: Metadata:
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: title : Video
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16p, 128 kb/s
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: Metadata:
15:39:41 T:140183896807168 INFO: ffmpeg[1B5F5700]: title : Audio
15:39:41 T:140183896807168 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
15:39:41 T:140183896807168 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
15:39:41 T:140183896807168 NOTICE: Opening video stream: 0 source: 256
15:39:41 T:140183896807168 NOTICE: Creating video codec with codec id: 13
15:39:41 T:140183896807168 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes
15:39:41 T:140183896807168 DEBUG: FactoryCodec - Video: - Opening
15:39:41 T:140183896807168 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/postproc-52-x86_64-linux.so)
15:39:41 T:140183896807168 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/postproc-52-x86_64-linux.so
15:39:41 T:140183896807168 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swresample-0-x86_64-linux.so)
15:39:41 T:140183896807168 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swresample-0-x86_64-linux.so
15:39:41 T:140183896807168 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avfilter-3-x86_64-linux.so)
15:39:41 T:140183896807168 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avfilter-3-x86_64-linux.so
15:39:41 T:140183896807168 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2
15:39:41 T:140183896807168 DEBUG: CDVDVideoCodecFFmpeg::Open() Keep default threading 3 by videoplayer.useframemtdec
15:39:41 T:140183896807168 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
15:39:41 T:140184682026752 NOTICE: Thread VideoReferenceClock start, auto delete: false
15:39:41 T:140184682026752 DEBUG: CVideoReferenceClock: Setting up GLX
15:39:42 T:140184682026752 DEBUG: CVideoReferenceClock: Detected refreshrate: 60 hertz
15:39:42 T:140183896807168 NOTICE: Creating video thread
15:39:42 T:140184673634048 NOTICE: Thread DVDPlayerVideo start, auto delete: false
15:39:42 T:140184673634048 NOTICE: running thread: video_thread
15:39:42 T:140183896807168 NOTICE: Opening audio stream: 1 source: 256
15:39:42 T:140183896807168 NOTICE: Finding audio codec for: 86017
15:39:42 T:140183896807168 DEBUG: FactoryCodec - Audio: passthrough - Opening
15:39:42 T:140183896807168 DEBUG: FactoryCodec - Audio: passthrough - Failed
15:39:42 T:140183896807168 DEBUG: FactoryCodec - Audio: libmad - Opening
15:39:42 T:140183896807168 DEBUG: SECTION:LoadDLL(libmad.so.0)
15:39:42 T:140183896807168 DEBUG: Loading: libmad.so.0
15:39:42 T:140184673634048 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
15:39:42 T:140183896807168 DEBUG: FactoryCodec - Audio: libmad - Opened
15:39:42 T:140183896807168 NOTICE: Creating audio thread
15:39:42 T:140184662943488 NOTICE: Thread DVDPlayerAudio start, auto delete: false
15:39:42 T:140184662943488 NOTICE: running thread: CDVDPlayerAudio::Process()
15:39:42 T:140183896807168 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: nfs://desktop/home/evan/documents/multimedia/videos/movies/This is Spinal Tap.avi
15:39:42 T:140183896807168 DEBUG: OnPlayBackStarted : play state was 1, starting 1
15:39:42 T:140183896807168 DEBUG: CDVDPlayer::SetCaching - caching state 3
15:39:42 T:140184673634048 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
15:39:42 T:140184673634048 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(41708.333333, 0)
15:39:42 T:140184662943488 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(24000.000000, 1)
15:39:42 T:140184662943488 DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough
15:39:42 T:140184662943488 DEBUG: FactoryCodec - Audio: passthrough - Opening
15:39:42 T:140184662943488 DEBUG: FactoryCodec - Audio: passthrough - Failed
15:39:42 T:140184673634048 NOTICE: fps: 23.976024, pwidth: 640, pheight: 352, dwidth: 640, dheight: 352
15:39:42 T:140184673634048 DEBUG: OutputPicture - change configuration. 640x352. framerate: 23.98. format: YV12
15:39:42 T:140184673634048 NOTICE: Display resolution ADJUST : HDMI-0: 1920x1080 @ 23.98Hz (22) (weight: 0.000)
15:39:42 T:140184673634048 DEBUG: CXBMCRenderManager::Configure - 3
15:39:42 T:140184673634048 DEBUG: CVideoReferenceClock: Clock speed 100.100000%
15:39:42 T:140184662943488 DEBUG: FactoryCodec - Audio: libmad - Opening
15:39:42 T:140184662943488 DEBUG: FactoryCodec - Audio: libmad - Opened
15:39:42 T:140184662943488 NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 48000, no pass-through)
15:39:42 T:140184971536128 INFO: CActiveAESink::OpenSink - initialize sink
15:39:42 T:140184662943488 DEBUG: CDVDPlayerAudio:: synctype set to 2: resample
15:39:42 T:140184971536128 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
15:39:42 T:140184971536128 DEBUG: PulseAudio: Context authorizing
15:39:42 T:140184971536128 DEBUG: PulseAudio: Context setting name
15:39:42 T:140184971536128 DEBUG: PulseAudio: Context ready
15:39:42 T:140184971536128 DEBUG: PulseAudio: Stream ready
15:39:42 T:140184971536128 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
15:39:42 T:140184971536128 DEBUG: Output Device : Default
15:39:42 T:140184971536128 DEBUG: Sample Rate : 48000
15:39:42 T:140184971536128 DEBUG: Sample Format : AE_FMT_FLOAT
15:39:42 T:140184971536128 DEBUG: Channel Count : 6
15:39:42 T:140184971536128 DEBUG: Channel Layout: FL,FR,FC,BL,BR,LFE
15:39:42 T:140184971536128 DEBUG: Frames : 500
15:39:42 T:140184971536128 DEBUG: Frame Samples : 3000
15:39:42 T:140184971536128 DEBUG: Frame Size : 24
15:39:42 T:140185377363904 NOTICE: Using GL_TEXTURE_2D
15:39:42 T:140185377363904 DEBUG: GL: Requested render method: 0
15:39:42 T:140184923256576 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:39:42 T:140185377363904 DEBUG: Previous line repeats 1 times.
15:39:42 T:140185377363904 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_YV12
15:39:42 T:140185377363904 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
15:39:42 T:140183896807168 DEBUG: CDVDPlayer::HandleMessages - player started 1
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compiled successfully
15:39:42 T:140185377363904 DEBUG: GL: Pixel Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Fragment Shader compiled successfully
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compiled successfully
15:39:42 T:140185377363904 DEBUG: GL: Pixel Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Fragment Shader compiled successfully
15:39:42 T:140185377363904 NOTICE: GL: NPOT texture support detected
15:39:42 T:140185377363904 NOTICE: GL: Using GL_ARB_pixel_buffer_object
15:39:42 T:140183896807168 DEBUG: CDVDPlayer::HandleMessages - player started 2
15:39:42 T:140183896807168 DEBUG: CDVDPlayer::SetCaching - caching state 0
15:39:42 T:140184662943488 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:24376.313000, should be:12273.734876, error:-12102.578124
15:39:42 T:140185377363904 DEBUG: Activating window ID: 12005
15:39:42 T:140185377363904 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
15:39:42 T:140185377363904 DEBUG: OnLostDevice - notify display change event
15:39:42 T:140185377363904 DEBUG: Flush - flushing renderer
15:39:42 T:140185377363904 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI-0 --mode 0x5c
15:39:42 T:140185377363904 ERROR: GLX: Same window as before, refreshing context
15:39:42 T:140185377363904 INFO: GL: Maximum texture width: 16384
15:39:42 T:140185377363904 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
15:39:42 T:140185377363904 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
15:39:42 T:140185377363904 DEBUG: Window VideoFullScreen.xml was already loaded
15:39:42 T:140185377363904 DEBUG: Alloc resources: 0.91m
15:39:42 T:140185377363904 DEBUG: PlayFile : OpenFile succeed, play state 2
15:39:42 T:140185377363904 DEBUG: OnPlayBackStarted : play state was 2, starting 0
15:39:42 T:140185377363904 NOTICE: Using GL_TEXTURE_2D
15:39:42 T:140185377363904 DEBUG: GL: Requested render method: 0
15:39:42 T:140185377363904 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_YV12
15:39:42 T:140185377363904 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compiled successfully
15:39:42 T:140185377363904 DEBUG: GL: Pixel Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Fragment Shader compiled successfully
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Vertex Shader compiled successfully
15:39:42 T:140185377363904 DEBUG: GL: Pixel Shader compilation log:
15:39:42 T:140185377363904 DEBUG: GL: Fragment Shader compiled successfully
15:39:42 T:140185377363904 NOTICE: GL: NPOT texture support detected
15:39:42 T:140185377363904 NOTICE: GL: Using GL_ARB_pixel_buffer_object
15:39:42 T:140185377363904 DEBUG: CheckDisplayEvents: Received RandR event 89
15:39:42 T:140185377363904 DEBUG: CheckDisplayEvents - notify display reset event
15:39:42 T:140185377363904 DEBUG: CGUIInfoManager::SetCurrentMovie(nfs://desktop/home/evan/documents/multimedia/videos/movies/This is Spinal Tap.avi)
15:39:42 T:140185377363904 DEBUG: GetMovieId (nfs://desktop/home/evan/documents/multimedia/videos/movies/This is Spinal Tap.avi), query = select idMovie from movie where idFile=3194
15:39:42 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
15:39:42 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
15:39:42 T:140184682026752 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 2, refreshrate might have changed
15:39:42 T:140185377363904 DEBUG: Previous line repeats 10 times.
15:39:42 T:140185377363904 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
15:39:42 T:140184682026752 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 2, refreshrate might have changed
15:39:43 T:140184682026752 DEBUG: Previous line repeats 14 times.
15:39:43 T:140184682026752 DEBUG: CVideoReferenceClock: Received RandR event 89
15:39:43 T:140184682026752 DEBUG: CVideoReferenceClock: Detected refreshrate: 24 hertz
15:39:43 T:140184662943488 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1152541.328876, should be:1258423.381635, error:105882.052759
15:39:44 T:140184662943488 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1789856.529635, should be:1898400.570774, error:108544.041139
15:39:47 T:140184673634048 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
15:39:47 T:140185377363904 DEBUG: Joystick 0 button 1 Down
15:39:47 T:140185377363904 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
15:39:47 T:140185377363904 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
15:39:47 T:140185377363904 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
15:39:47 T:140185377363904 DEBUG: Window DialogSeekBar.xml was already loaded
15:39:47 T:140185377363904 DEBUG: Alloc resources: 0.02m
15:39:47 T:140185377363904 DEBUG: Joystick 0 button 1 Up
15:40:10 T:140184305731328 DEBUG: Thread JobWorker 140184305731328 terminating (autodelete)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment