XBMC occassionally shows BUSY label and becomes unresponsive - cogano - 2014-10-29
Hello,
XBMC quite often becomes unresponsive instead of executing suspend on idle.
It just displays a label in bottom right-hand side of the screen with "Busy" text (or "Working" - I use non-English locale, sorry) and nothing happens. Since then, it does not react to any key-press. It still displays mouse pointer movements, but it does not react to mouse clicks either.
It does not seem to be completely frozen since it still continues to display the RSS feed and the current time. Also, I can stop XBMC from CLI with the friendly command "kill -15 `pidof xbmc-bin`".
I activated the debug mode and the last message before XBMC gets unresponsive is (the time is when it should suspend):
Code: 18:49:35 T:2996303680 DEBUG: ------ Window Init (DialogBusy.xml) ------
For comparison: in case the standard suspend process continues properly, the messages are like:
Code: 18:25:46 T:2995971904 DEBUG: ------ Window Init (DialogBusy.xml) ------
18:25:47 T:2995971904 DEBUG: CAnnouncementManager - Announcement: OnSleep from xbmc
18:25:47 T:2995971904 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnSleep
18:25:47 T:2995971904 NOTICE: OnSleep: Running sleep jobs
18:25:47 T:2995971904 NOTICE: OnSleep: Stopping lirc
... cut on purpose ...
After sending the SIGTERM (kill -15 ...) to the non-responsive XBMC, the log is filled with standard shutdown messages.
Code: 18:51:10 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc
18:51:10 T:2996303680 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnQuit
18:51:10 T:2996303680 NOTICE: Storing total System Uptime
18:51:10 T:2748459840 DEBUG: Thread AlarmClock 2748459840 terminating
18:51:10 T:2996303680 NOTICE: Saving settings
18:51:10 T:2996303680 NOTICE: stop all
18:51:10 T:2996303680 NOTICE: stop player
18:51:10 T:2996303680 INFO: stopping PVRManager
18:51:10 T:2996303680 DEBUG: NetworkMessage - Signaling network services to stop
18:51:10 T:2996303680 NOTICE: ES: Stopping event server
18:51:10 T:2996303680 DEBUG: NetworkMessage - Waiting for network services to stop
18:51:10 T:2996303680 NOTICE: Webserver: Stopping...
18:51:10 T:2996303680 NOTICE: WebServer: Stopped the webserver
18:51:10 T:2996303680 NOTICE: Webserver: Stopped...
18:51:11 T:2756852544 DEBUG: Thread TCPServer 2756852544 terminating
18:51:11 T:2765245248 NOTICE: ES: UDP Event server stopped
18:51:11 T:2765245248 DEBUG: Thread EventServer 2765245248 terminating
18:51:11 T:2996303680 NOTICE: stop dvd detect media
18:51:11 T:2906651456 DEBUG: Thread PeripBusUSBUdev 2906651456 terminating
18:51:11 T:2996303680 NOTICE: stop sap announcement listener
18:51:11 T:2916461376 DEBUG: Thread PeripBusCEC 2916461376 terminating
18:51:11 T:2996303680 NOTICE: clean cached files!
18:51:11 T:2996303680 NOTICE: unload skin
18:51:11 T:2996303680 INFO: Unloading old skin ...
18:51:11 T:2996303680 DEBUG: ------ Window Deinit () ------
18:51:11 T:2996303680 DEBUG: Previous line repeats 1 times.
18:51:11 T:2996303680 DEBUG: ------ Window Deinit (Home.xml) ------
18:51:11 T:2996303680 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:51:11 T:2996303680 DEBUG: Cleanup - Closed bundle
... cut on purpose ...
I use XBMC 2:13.2~git201408 i386 on Ubuntu 14.04. Confluence skin. I tried to deactive Myth PVR and Weather Plugins, but still no help.
Any ideas how to debug further or workaround the problem?
RE: XBMC occassionally shows BUSY label and becomes unresponsive - fritsch - 2014-10-29
Don't cut logfiles, please. If you are running on AMD's fglrx for example - such behaviour is just normal.
RE: XBMC occassionally shows BUSY label and becomes unresponsive - cogano - 2014-11-01
Sorry, here is the full session log of the above described problem. BTW, XBMC runs on Zotac ION (Intel Atom + nVidia ION) with nvidia 331.38-0ubuntu7.1 package.
Due to length, the log is split to two parts:
Code: 18:41:41 T:2996303680 NOTICE: special://profile/ is mapped to: special://masterprofile/
18:41:41 T:2996303680 NOTICE: -----------------------------------------------------------------------
18:41:41 T:2996303680 NOTICE: Starting XBMC (13.2 Git:0f3db05). Platform: x86 Linux 32-bit
18:41:41 T:2996303680 NOTICE: Using Release XBMC x32 build, compiled Aug 17 2014 by GCC 4.8.2 for x86 Linux 32-bit 3.13.11
18:41:41 T:2996303680 NOTICE: Running on Linux 32-bit (Ubuntu 14.04.1 LTS, 3.13.0-29-generic i686)
18:41:41 T:2996303680 NOTICE: Host CPU: Intel(R) Atom(TM) CPU 330 @ 1.60GHz, 4 cores available
18:41:41 T:2996303680 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
18:41:41 T:2996303680 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
18:41:41 T:2996303680 NOTICE: special://masterprofile/ is mapped to: /home/myth01/.xbmc/userdata
18:41:41 T:2996303680 NOTICE: special://home/ is mapped to: /home/myth01/.xbmc
18:41:41 T:2996303680 NOTICE: special://temp/ is mapped to: /home/myth01/.xbmc/temp
18:41:41 T:2996303680 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
18:41:41 T:2996303680 NOTICE: Local hostname: myth01
18:41:41 T:2996303680 NOTICE: Log File is located: /home/myth01/.xbmc/temp/xbmc.log
18:41:41 T:2996303680 NOTICE: -----------------------------------------------------------------------
18:41:53 T:2996303680 NOTICE: load settings...
18:41:53 T:2996303680 ERROR: PulseAudio: Failed to connect context
18:41:53 T:2996303680 NOTICE: PulseAudio might not be running. Context was not created.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_LPCM" on device "hdmi_dmix" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTSHD" on device "hdmi_dmix" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_TRUEHD" on device "hdmi_dmix" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_EAC3" on device "hdmi_dmix" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTS" on device "hdmi_dmix" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AC3" on device "hdmi_dmix" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AAC" on device "hdmi_dmix" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_LPCM" on device "hdmi_formatted" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTSHD" on device "hdmi_formatted" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_TRUEHD" on device "hdmi_formatted" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_EAC3" on device "hdmi_formatted" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTS" on device "hdmi_formatted" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AC3" on device "hdmi_formatted" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AAC" on device "hdmi_formatted" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_LPCM" on device "hdmi_complete" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTSHD" on device "hdmi_complete" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_TRUEHD" on device "hdmi_complete" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_EAC3" on device "hdmi_complete" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTS" on device "hdmi_complete" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AC3" on device "hdmi_complete" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AAC" on device "hdmi_complete" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_LPCM" on device "hdmi:CARD=NVidia,DEV=0" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTSHD" on device "hdmi:CARD=NVidia,DEV=0" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_TRUEHD" on device "hdmi:CARD=NVidia,DEV=0" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_EAC3" on device "hdmi:CARD=NVidia,DEV=0" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTS" on device "hdmi:CARD=NVidia,DEV=0" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AC3" on device "hdmi:CARD=NVidia,DEV=0" seems to be not supported.
18:41:53 T:2996303680 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AAC" on device "hdmi:CARD=NVidia,DEV=0" seems to be not supported.
18:41:53 T:2996303680 NOTICE: Found 1 Lists of Devices
18:41:53 T:2996303680 NOTICE: Enumerated ALSA devices:
18:41:53 T:2996303680 NOTICE: Device 1
18:41:53 T:2996303680 NOTICE: m_deviceName : @
18:41:53 T:2996303680 NOTICE: m_displayName : Default (HDA NVidia ALC662 rev1 Analog) ()
18:41:53 T:2996303680 NOTICE: m_displayNameExtra:
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_PCM
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 48000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_S32NE
18:41:53 T:2996303680 NOTICE: Device 2
18:41:53 T:2996303680 NOTICE: m_deviceName : dsp
18:41:53 T:2996303680 NOTICE: m_displayName : HDA NVidia ()
18:41:53 T:2996303680 NOTICE: m_displayNameExtra: ALC662 rev1 Analog
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_PCM
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 48000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_S32NE
18:41:53 T:2996303680 NOTICE: Device 3
18:41:53 T:2996303680 NOTICE: m_deviceName : hdmi_dmix
18:41:53 T:2996303680 NOTICE: m_displayName : HDA NVidia (hdmi_dmix) ()
18:41:53 T:2996303680 NOTICE: m_displayNameExtra: HDMI
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 48000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S16NE,AE_FMT_S16LE
18:41:53 T:2996303680 NOTICE: Device 4
18:41:53 T:2996303680 NOTICE: m_deviceName : hdmi_formatted
18:41:53 T:2996303680 NOTICE: m_displayName : HDA NVidia (hdmi_formatted) ()
18:41:53 T:2996303680 NOTICE: m_displayNameExtra: HDMI
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 48000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S16NE,AE_FMT_S16LE
18:41:53 T:2996303680 NOTICE: Device 5
18:41:53 T:2996303680 NOTICE: m_deviceName : hdmi_complete
18:41:53 T:2996303680 NOTICE: m_displayName : HDA NVidia ()
18:41:53 T:2996303680 NOTICE: m_displayNameExtra: HDMI
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 48000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S16NE,AE_FMT_S16LE
18:41:53 T:2996303680 NOTICE: Device 6
18:41:53 T:2996303680 NOTICE: m_deviceName : @:CARD=NVidia,DEV=0
18:41:53 T:2996303680 NOTICE: m_displayName : HDA NVidia (NVidia)
18:41:53 T:2996303680 NOTICE: m_displayNameExtra: ALC662 rev1 Analog
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_PCM
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 48000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_S32NE
18:41:53 T:2996303680 NOTICE: Device 7
18:41:53 T:2996303680 NOTICE: m_deviceName : iec958:CARD=NVidia,DEV=0
18:41:53 T:2996303680 NOTICE: m_displayName : HDA NVidia (NVidia)
18:41:53 T:2996303680 NOTICE: m_displayNameExtra: ALC662 rev1 Digital S/PDIF
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_IEC958
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 44100,48000,96000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_AC3,AE_FMT_DTS,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
18:41:53 T:2996303680 NOTICE: Device 8
18:41:53 T:2996303680 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0
18:41:53 T:2996303680 NOTICE: m_displayName : HDA NVidia (NVidia)
18:41:53 T:2996303680 NOTICE: m_displayNameExtra: HDMI
18:41:53 T:2996303680 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:41:53 T:2996303680 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
18:41:53 T:2996303680 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
18:41:53 T:2996303680 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
18:41:53 T:2996303680 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
18:41:53 T:2996303680 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
18:41:53 T:2996303680 NOTICE: Default DVD Player: dvdplayer
18:41:53 T:2996303680 NOTICE: Default Video Player: dvdplayer
18:41:53 T:2996303680 NOTICE: Default Audio Player: paplayer
18:41:53 T:2996303680 NOTICE: Enabled debug logging due to GUI setting (2)
18:41:53 T:2996303680 NOTICE: Log level changed to 2
18:41:53 T:2996303680 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
18:41:53 T:2996303680 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
18:41:53 T:2996303680 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
18:41:53 T:2996303680 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
18:41:53 T:2996303680 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
18:41:53 T:2996303680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
18:41:53 T:2996303680 NOTICE: Loaded playercorefactory configuration
18:41:53 T:2996303680 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
18:41:53 T:2996303680 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
18:41:53 T:2996303680 INFO: creating subdirectories
18:41:53 T:2996303680 INFO: userdata folder: special://masterprofile/
18:41:53 T:2996303680 INFO: recording folder:
18:41:53 T:2996303680 INFO: screenshots folder:
18:41:53 T:2996303680 INFO: load language info file: special://xbmc/language/Czech/langinfo.xml
18:41:53 T:2996303680 DEBUG: trying to set locale to cs_CZ.UTF-8
18:41:53 T:2996303680 INFO: global locale set to cs_CZ.UTF-8
18:41:53 T:2996303680 INFO: load Czech language file, from path: special://xbmc/language/
18:41:53 T:2996303680 DEBUG: POParser: loaded 3162 strings from file /usr/share/xbmc/language/Czech/strings.po
18:41:53 T:2996303680 DEBUG: POParser: loaded 0 strings from file /usr/share/xbmc/language/English/strings.po
18:41:53 T:2861546304 NOTICE: Thread ActiveAE start, auto delete: false
18:41:53 T:2926934848 NOTICE: Thread AESink start, auto delete: false
18:41:53 T:2926934848 INFO: CActiveAESink::OpenSink - initialize sink
18:41:53 T:2926934848 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi_complete
18:41:53 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:41:53 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
18:41:53 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
18:41:53 T:2926934848 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi_complete"
18:41:53 T:2926934848 INFO: CAESinkALSA - Unable to open device "hdmi_complete:AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" for playback
18:41:53 T:2926934848 INFO: CAESinkALSA::Initialize - Opened device "hdmi_complete"
18:41:53 T:2926934848 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:41:53 T:2926934848 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
18:41:53 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
18:41:53 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Request: Failed to limit periodSize to 3200
18:41:53 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 6016, bufferSize 12032
18:41:53 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 251 ms
18:41:53 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:41:53 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
18:41:53 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
18:41:53 T:2926934848 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
18:41:53 T:2926934848 DEBUG: Output Device : HDA NVidia ()
18:41:53 T:2926934848 DEBUG: Sample Rate : 48000
18:41:53 T:2926934848 DEBUG: Sample Format : AE_FMT_S16NE
18:41:53 T:2926934848 DEBUG: Channel Count : 2
18:41:53 T:2926934848 DEBUG: Channel Layout: FL,FR
18:41:53 T:2926934848 DEBUG: Frames : 6016
18:41:53 T:2926934848 DEBUG: Frame Samples : 12032
18:41:53 T:2926934848 DEBUG: Frame Size : 4
18:41:53 T:2996303680 NOTICE: Running database version Addons16
18:41:53 T:2996303680 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-i486-linux.so)
18:41:53 T:2996303680 DEBUG: Loading: /usr/lib/xbmc/system/libcpluff-i486-linux.so
18:41:54 T:2996303680 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/share/xbmc/addons: No such file or directory'
18:41:54 T:2996303680 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/xbmc/addons: No such file or directory'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.t0mm0.common has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in service.autosubs has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.fishbmc has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in weather.yahoo has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmchub has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.simple.downloader has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.csfd.cz has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in service.subtitles.opensubtitles has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.urlresolver has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.navi-x has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.movieposterdb.com has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in webinterface.XWMM has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in pvr.mythtv.cmyth has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.debug has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
18:41:54 T:2996303680 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
18:41:54 T:2996303680 NOTICE: ADDONS: Using repository repository.xbmc.org
18:41:54 T:2996303680 NOTICE: ADDONS: Using repository repository.xbmchub
18:41:54 T:2996303680 INFO: LIRC Initialize: using: /dev/lircd
18:41:54 T:2996303680 INFO: LIRC Initialize: connect failed: No such file or directory
18:41:54 T:2996303680 DEBUG: Failed to connect to LIRC. Retry in 10s.
18:41:54 T:2996303680 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
18:41:54 T:2996303680 DEBUG: LoadMappings - loaded node "CEC Adapter"
18:41:54 T:2996303680 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
18:41:54 T:2996303680 DEBUG: LoadMappings - loaded node "iMON HID device"
18:41:54 T:2996303680 DEBUG: CPeripheralBusUSB - initialised udev monitor
18:41:54 T:2996303680 DEBUG: SECTION:LoadDLL(libcec.so.2)
18:41:54 T:2996303680 DEBUG: Loading: libcec.so.2
18:41:54 T:2916461376 NOTICE: Thread PeripBusCEC start, auto delete: false
18:41:54 T:2906651456 NOTICE: Thread PeripBusUSBUdev start, auto delete: false
18:41:54 T:2996303680 DEBUG: Selected UDisks as storage provider
18:41:54 T:2996303680 DEBUG: UDisks: DaemonVersion 1
18:41:54 T:2996303680 DEBUG: UDisks: Querying available devices
18:41:54 T:2996303680 INFO: UDisks: Added /mythtv
18:41:54 T:2996303680 NOTICE: Setup SDL
18:41:54 T:2996303680 INFO: Available videomodes (xrandr):
18:41:54 T:2996303680 INFO: Number of connected outputs: 1
18:41:54 T:2996303680 INFO: Output 'HDMI-0' has 33 modes
18:41:54 T:2996303680 INFO: ID:0x1c7 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1c8 Name:1920x1080 Refresh:59.939388 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1c9 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1ca Name:1920x1080 Refresh:29.971720 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1cb Name:1920x1080 Refresh:25.000000 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1cc Name:1920x1080 Refresh:23.970909 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1cd Name:1920x1080 Refresh:30.026690 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1ce Name:1920x1080 Refresh:29.998381 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1cf Name:1920x1080 Refresh:25.022240 Width:1920 Height:1080
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1d0 Name:1680x1050 Refresh:59.954250 Width:1680 Height:1050
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.111111
18:41:54 T:2996303680 INFO: ID:0x1d1 Name:1600x1200 Refresh:60.000000 Width:1600 Height:1200
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1d2 Name:1440x900 Refresh:74.984428 Width:1440 Height:900
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.111111
18:41:54 T:2996303680 INFO: ID:0x1d3 Name:1440x900 Refresh:59.887451 Width:1440 Height:900
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.111111
18:41:54 T:2996303680 INFO: ID:0x1d4 Name:1360x768 Refresh:60.015160 Width:1360 Height:768
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.003922
18:41:54 T:2996303680 INFO: ID:0x1d5 Name:1280x1024 Refresh:75.024673 Width:1280 Height:1024
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.422222
18:41:54 T:2996303680 INFO: ID:0x1d6 Name:1280x1024 Refresh:60.019741 Width:1280 Height:1024
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.422222
18:41:54 T:2996303680 INFO: ID:0x1d7 Name:1280x960 Refresh:60.000000 Width:1280 Height:960
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1d8 Name:1280x800 Refresh:59.810329 Width:1280 Height:800
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.111111
18:41:54 T:2996303680 INFO: ID:0x1d9 Name:1280x720 Refresh:60.000000 Width:1280 Height:720
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1da Name:1280x720 Refresh:59.943432 Width:1280 Height:720
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1db Name:1280x720 Refresh:50.000000 Width:1280 Height:720
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.000000
18:41:54 T:2996303680 INFO: ID:0x1dc Name:1152x864 Refresh:75.000000 Width:1152 Height:864
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1dd Name:1024x768 Refresh:75.028580 Width:1024 Height:768
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1de Name:1024x768 Refresh:70.069359 Width:1024 Height:768
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1df Name:1024x768 Refresh:60.003841 Width:1024 Height:768
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1e0 Name:800x600 Refresh:75.000000 Width:800 Height:600
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1e1 Name:800x600 Refresh:72.187569 Width:800 Height:600
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1e2 Name:800x600 Refresh:60.316540 Width:800 Height:600
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1e3 Name:720x576 Refresh:50.000000 Width:720 Height:576
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.422222
18:41:54 T:2996303680 INFO: ID:0x1e4 Name:720x480 Refresh:59.940060 Width:720 Height:480
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.185185
18:41:54 T:2996303680 INFO: ID:0x1e5 Name:640x480 Refresh:75.000000 Width:640 Height:480
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1e6 Name:640x480 Refresh:72.808800 Width:640 Height:480
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 INFO: ID:0x1e7 Name:640x480 Refresh:59.940479 Width:640 Height:480
18:41:54 T:2996303680 INFO: Pixel Ratio: 1.333333
18:41:54 T:2996303680 NOTICE: Checking resolution 16
18:41:54 T:2996303680 DEBUG: OnLostDevice - notify display change event
18:41:54 T:2996303680 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI-0 --mode 0x1c7
18:41:55 T:2996303680 NOTICE: Using visual 0x6a
18:41:55 T:2996303680 INFO: GL: Maximum texture width: 8192
18:41:55 T:2996303680 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-i486-linux.so)
18:41:55 T:2996303680 DEBUG: Loading: /usr/lib/xbmc/system/ImageLib-i486-linux.so
18:41:55 T:2996303680 DEBUG: GLX_EXTENSIONS: GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control GLX_EXT_swap_control GLX_EXT_swap_control_tear GLX_EXT_texture_from_pixmap GLX_EXT_buffer_age GLX_ARB_create_context GLX_ARB_create_context_profile GLX_EXT_create_context_es_profile GLX_EXT_create_context_es2_profile GLX_ARB_create_context_robustness GLX_ARB_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_EXT_framebuffer_sRGB GLX_NV_multisample_coverage GLX_ARB_get_proc_address
18:41:55 T:2996303680 NOTICE: GL_VENDOR = NVIDIA Corporation
18:41:55 T:2996303680 NOTICE: GL_RENDERER = ION/integrated/SSE2
18:41:55 T:2996303680 NOTICE: GL_VERSION = 3.3.0 NVIDIA 331.38
18:41:55 T:2996303680 NOTICE: GL_SHADING_LANGUAGE_VERSION = 3.30 NVIDIA via Cg compiler
18:41:55 T:2996303680 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 524288
18:41:55 T:2996303680 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 524288
18:41:55 T:2996303680 NOTICE: GL_EXTENSIONS = GL_ARB_arrays_of_arrays GL_ARB_base_instance GL_ARB_blend_func_extended GL_ARB_clear_buffer_object GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_copy_buffer GL_ARB_copy_image GL_ARB_debug_output GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_enhanced_layouts GL_ARB_ES2_compatibility GL_ARB_ES3_compatibility GL_ARB_explicit_attrib_location GL_ARB_explicit_uniform_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_layer_viewport GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_no_attachments GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_internalformat_query2 GL_ARB_invalidate_subdata GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multi_bind GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_program_interface_query GL_ARB_provoking_vertex GL_ARB_robust_buffer_access_behavior GL_ARB_robustness GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_objects GL_ARB_shader_texture_lod GL_ARB_shading_language_100 GL_ARB_shading_language_420pack GL_ARB_shading_language_include GL_ARB_shading_language_packing GL_ARB_shadow GL_ARB_stencil_texturing GL_ARB_sync GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_range GL_ARB_texture_compression GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirror_clamp_to_edge GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_levels GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_stencil8 GL_ARB_texture_storage GL_ARB_texture_storage_multisample GL_ARB_texture_swizzle GL_ARB_texture_view GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback_instanced GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_attrib_binding GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_vertex_type_10f_11f_11f_rev GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_bindable_uniform GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent GL_EXT_texture_sRGB GL_EXT_texture_sRGB_decode GL_EXT_texture_storage GL_EXT_texture_swizzle GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KHR_debug GL_KTX_buffer_region GL_NV_blend_square GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_ES1_1_compatibility GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_path_rendering GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_buffer_load GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
18:41:55 T:2996303680 DEBUG: OnLostDevice - notify display change event
18:41:55 T:2996303680 ERROR: GLX: Same window as before, refreshing context
18:41:55 T:2996303680 INFO: GL: Maximum texture width: 8192
18:41:55 T:2996303680 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
18:41:55 T:2996303680 INFO: load default splash image: /usr/share/xbmc/media/Splash.png
18:41:55 T:2996303680 DEBUG: CheckDisplayEvents: Received RandR event 89
18:41:55 T:2996303680 DEBUG: CheckDisplayEvents - notify display reset event
18:41:55 T:2861546304 DEBUG: CActiveAE - display reset event
18:41:55 T:2996303680 INFO: load keymapping
18:41:55 T:2926934848 INFO: CActiveAESink::OpenSink - initialize sink
18:41:55 T:2926934848 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi_complete
18:41:55 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:41:55 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
18:41:55 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
18:41:55 T:2926934848 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi_complete"
18:41:55 T:2926934848 INFO: CAESinkALSA - Unable to open device "hdmi_complete:AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" for playback
18:41:55 T:2926934848 INFO: CAESinkALSA::Initialize - Opened device "hdmi_complete"
18:41:55 T:2926934848 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:41:55 T:2926934848 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
18:41:55 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
18:41:55 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Request: Failed to limit periodSize to 3200
18:41:55 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 6016, bufferSize 12032
18:41:55 T:2926934848 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 251 ms
18:41:55 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:41:55 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
18:41:55 T:2926934848 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
18:41:55 T:2926934848 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
18:41:55 T:2926934848 DEBUG: Output Device : HDA NVidia ()
18:41:55 T:2926934848 DEBUG: Sample Rate : 48000
18:41:55 T:2926934848 DEBUG: Sample Format : AE_FMT_S16NE
18:41:55 T:2926934848 DEBUG: Channel Count : 2
18:41:55 T:2926934848 DEBUG: Channel Layout: FL,FR
18:41:55 T:2926934848 DEBUG: Frames : 6016
18:41:55 T:2926934848 DEBUG: Frame Samples : 12032
18:41:55 T:2926934848 DEBUG: Frame Size : 4
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
18:41:55 T:2861546304 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Nintendo.Wii.U.Pro.Controller.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/joystick.xml
18:41:55 T:2996303680 NOTICE: No Joystick name specified, loading default map
18:41:55 T:2996303680 NOTICE: Previous line repeats 35 times.
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/mouse.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/remote.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
18:41:55 T:2996303680 INFO: Loading special://masterprofile/keymaps/keyboard.xml
18:41:55 T:2996303680 INFO: Loading special://profile/keymaps/keyboard.xml
18:41:55 T:2996303680 INFO: Loading special://xbmc/system/Lircmap.xml
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'mceusb'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'XboxDVDDongle'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'anysee'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'iMON-PAD'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'MCE_via_iMON'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'TwinHanRemote'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'linux-input-layer'
18:41:55 T:2996303680 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'mediacenter'
18:41:55 T:2996303680 INFO: * Adding remote mapping for device 'devinput'
18:41:55 T:2996303680 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
18:41:55 T:2996303680 INFO: GUI format 1920x1080, Display 1920x1080@ 60.00 - Full Screen
18:41:55 T:2996303680 DEBUG: guilib: Fill viewport on change for solving rendering passes
18:41:55 T:2996303680 DEBUG: SECTION:LoadDLL(libcurl.so.4)
18:41:55 T:2996303680 DEBUG: Loading: libcurl.so.4
18:41:55 T:2996303680 NOTICE: Running database version Addons16
18:41:55 T:2996303680 DEBUG: Initialize, updating databases...
18:41:55 T:2996303680 NOTICE: Running database version ViewModes6
18:41:55 T:2996303680 NOTICE: Running database version Textures13
18:41:55 T:2996303680 NOTICE: Running database version MyMusic46
18:41:55 T:2996303680 NOTICE: Running database version MyVideos78
18:41:55 T:2996303680 NOTICE: Running database version TV22
18:41:55 T:2996303680 NOTICE: Running database version Epg7
18:41:55 T:2996303680 DEBUG: Initialize, updating databases... DONE
18:41:55 T:2996303680 NOTICE: start dvd mediatype detection
18:41:55 T:2907839296 NOTICE: Thread DetectDVDMedia start, auto delete: false
18:41:55 T:2907839296 DEBUG: Compiled with libcdio Version 0.83
18:41:55 T:2996303680 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
18:41:55 T:2907839296 DEBUG: Thread DetectDVDMedia 2907839296 terminating
18:41:55 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:41:55 T:2996303680 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
18:41:55 T:2996303680 INFO: Unloading old skin ...
18:41:55 T:2996303680 INFO: load skin from: /usr/share/xbmc/addons/skin.confluence (version: 2.2.20)
18:41:55 T:2996303680 INFO: load fonts for skin...
18:41:55 T:2996303680 INFO: Loading fonts from /usr/share/xbmc/addons/skin.confluence/720p/Font.xml
18:41:55 T:2996303680 DEBUG: POParser: loaded 148 strings from file /usr/share/xbmc/addons/skin.confluence/language/Czech/strings.po
18:41:55 T:2996303680 DEBUG: POParser: loaded 0 strings from file /usr/share/xbmc/addons/skin.confluence/language/English/strings.po
18:41:55 T:2996303680 INFO: Loading skin includes from /usr/share/xbmc/addons/skin.confluence/720p/includes.xml
18:41:55 T:2996303680 INFO: load new skin...
18:41:55 T:2996303680 INFO: Loading user windows, path /usr/share/xbmc/addons/skin.confluence/720p
18:41:55 T:2996303680 DEBUG: Load Skin XML: 10.04ms
18:41:55 T:2996303680 INFO: initialize new skin...
18:41:55 T:2996303680 DEBUG: guilib: Fill viewport on change for solving rendering passes
18:41:55 T:2996303680 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
18:41:55 T:2996303680 DEBUG: OpenBundle - Opened bundle /usr/share/xbmc/addons/skin.confluence/media/Textures.xbt
18:41:55 T:2996303680 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
18:41:55 T:2996303680 INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
18:41:55 T:2996303680 INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
18:41:55 T:2996303680 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
18:41:55 T:2996303680 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
18:41:55 T:2996303680 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
18:41:55 T:2996303680 INFO: skin loaded...
18:41:55 T:2996303680 DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
18:41:55 T:2996303680 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
18:41:55 T:2996303680 INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
18:41:55 T:2996303680 INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
18:41:55 T:2996303680 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
18:41:55 T:2996303680 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
18:41:55 T:2996303680 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
18:41:55 T:2996303680 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
18:41:55 T:2996303680 INFO: JSONRPC v6.14.3: Successfully initialized
18:41:55 T:2996303680 DEBUG: ADDON: Starting service addons.
18:41:55 T:2879449920 NOTICE: Thread LanguageInvoker start, auto delete: false
18:41:55 T:2879449920 INFO: initializing python engine.
18:41:55 T:2871057216 NOTICE: Thread LanguageInvoker start, auto delete: false
18:41:55 T:2871057216 NOTICE: Previous line repeats 1 times.
18:41:55 T:2871057216 INFO: initializing python engine.
18:41:55 T:2996303680 INFO: Previous line repeats 1 times.
18:41:55 T:2996303680 DEBUG: Activating window ID: 12999
18:41:55 T:2996303680 DEBUG: ------ Window Init (Startup.xml) ------
18:41:55 T:2996303680 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
18:41:55 T:2996303680 INFO: removing tempfiles
18:41:55 T:2996303680 DEBUG: ADDON: Starting service addons.
18:41:55 T:2840587072 NOTICE: Thread LanguageInvoker start, auto delete: false
18:41:55 T:2840587072 INFO: initializing python engine.
18:41:55 T:2996303680 NOTICE: initialize done
18:41:55 T:2832194368 NOTICE: Thread LanguageInvoker start, auto delete: false
18:41:55 T:2996303680 NOTICE: Running the application...
18:41:55 T:2832194368 INFO: initializing python engine.
18:41:55 T:2996303680 DEBUG: Activating window ID: 10000
18:41:56 T:2871057216 DEBUG: CPythonInvoker(1, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): start processing
18:41:56 T:2848979776 DEBUG: CPythonInvoker(2, /home/myth01/.xbmc/addons/script.module.simple.downloader/default.py): start processing
18:41:56 T:2879449920 DEBUG: CPythonInvoker(0, /home/myth01/.xbmc/addons/service.autosubs/default.py): start processing
18:41:56 T:2840587072 DEBUG: CPythonInvoker(3, /home/myth01/.xbmc/addons/script.common.plugin.cache/default.py): start processing
18:41:56 T:2832194368 DEBUG: CPythonInvoker(4, /home/myth01/.xbmc/addons/Navi-X/service.py): start processing
18:41:56 T:2996303680 DEBUG: ------ Window Init () ------
18:41:56 T:2871057216 NOTICE: -->Python Interpreter Initialized<--
18:41:56 T:2871057216 DEBUG: CPythonInvoker(1, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py"
18:41:56 T:2871057216 DEBUG: CPythonInvoker(1, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/myth01/.xbmc/addons/service.xbmc.versioncheck:/home/myth01/.xbmc/addons/script.module.t0mm0.common/lib:/home/myth01/.xbmc/addons/script.module.simplejson/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/myth01/.xbmc/addons/script.common.plugin.cache/lib:/home/myth01/.xbmc/addons/script.module.simple.downloader/lib:/home/myth01/.xbmc/addons/script.module.urlresolver/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/myth01/.xbmc/addons/script.module.parsedom/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-i386-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:41:56 T:2871057216 DEBUG: CPythonInvoker(1, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): entering source directory /home/myth01/.xbmc/addons/service.xbmc.versioncheck
18:41:56 T:2996303680 DEBUG: ------ Window Init (Pointer.xml) ------
18:41:56 T:2871057216 DEBUG: CPythonInvoker(1, /home/myth01/.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
18:41:56 T:2996303680 DEBUG: ------ Window Deinit (Startup.xml) ------
18:41:56 T:2996303680 DEBUG: ------ Window Init (Home.xml) ------
18:41:56 T:2996303680 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
18:41:56 T:2879449920 NOTICE: -->Python Interpreter Initialized<--
18:41:56 T:2879449920 DEBUG: CPythonInvoker(0, /home/myth01/.xbmc/addons/service.autosubs/default.py): the source file to load is "/home/myth01/.xbmc/addons/service.autosubs/default.py"
18:41:56 T:2840587072 NOTICE: -->Python Interpreter Initialized<--
18:41:56 T:2840587072 DEBUG: CPythonInvoker(3, /home/myth01/.xbmc/addons/script.common.plugin.cache/default.py): the source file to load is "/home/myth01/.xbmc/addons/script.common.plugin.cache/default.py"
18:41:56 T:2871057216 DEBUG: XBMC Version Check: Version 0.1.23 started
18:41:56 T:2798816064 NOTICE: Thread JobWorker start, auto delete: true
18:41:56 T:2798816064 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
18:41:56 T:2798816064 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
18:41:56 T:2996303680 DEBUG: no profile autoexec.py (/home/myth01/.xbmc/userdata/autoexec.py) found, skipping
18:41:56 T:2996303680 DEBUG: NetworkMessage - Starting network services
18:41:56 T:2996303680 NOTICE: Webserver: Starting...
18:41:56 T:2871057216 INFO: CPythonInvoker(1, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): script successfully run
18:41:56 T:2848979776 NOTICE: -->Python Interpreter Initialized<--
18:41:56 T:2996303680 NOTICE: WebServer: Started the webserver
18:41:56 T:2848979776 DEBUG: CPythonInvoker(2, /home/myth01/.xbmc/addons/script.module.simple.downloader/default.py): the source file to load is "/home/myth01/.xbmc/addons/script.module.simple.downloader/default.py"
18:41:56 T:2832194368 NOTICE: -->Python Interpreter Initialized<--
18:41:56 T:2832194368 DEBUG: CPythonInvoker(4, /home/myth01/.xbmc/addons/Navi-X/service.py): the source file to load is "/home/myth01/.xbmc/addons/Navi-X/service.py"
18:41:56 T:2879449920 DEBUG: CPythonInvoker(0, /home/myth01/.xbmc/addons/service.autosubs/default.py): setting the Python path to /home/myth01/.xbmc/addons/service.autosubs:/home/myth01/.xbmc/addons/script.module.t0mm0.common/lib:/home/myth01/.xbmc/addons/script.module.simplejson/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/myth01/.xbmc/addons/script.common.plugin.cache/lib:/home/myth01/.xbmc/addons/script.module.simple.downloader/lib:/home/myth01/.xbmc/addons/script.module.urlresolver/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/myth01/.xbmc/addons/script.module.parsedom/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-i386-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:41:56 T:2879449920 DEBUG: CPythonInvoker(0, /home/myth01/.xbmc/addons/service.autosubs/default.py): entering source directory /home/myth01/.xbmc/addons/service.autosubs
18:41:56 T:2996303680 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
18:41:56 T:2996303680 NOTICE: ES: Starting event server
18:41:56 T:2996303680 INFO: JSONRPC Server: Successfully initialized
18:41:56 T:2765245248 NOTICE: Thread EventServer start, auto delete: false
18:41:56 T:2765245248 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
18:41:56 T:2765245248 NOTICE: UDP: Listening on port 9777
18:41:56 T:2996303680 DEBUG: started alarm with name: checkatboot
18:41:56 T:2996303680 DEBUG: started alarm with name: checkwhilerunning
18:41:56 T:2748459840 NOTICE: Thread AlarmClock start, auto delete: false
18:41:56 T:2756852544 NOTICE: Thread TCPServer start, auto delete: false
18:41:56 T:2907839296 NOTICE: Thread RSSReader start, auto delete: false
18:41:56 T:2907839296 DEBUG: CurlFile::Open(0xad5211b0) http://feeds.xbmc.org/xbmc
18:41:56 T:2907839296 INFO: easy_aquire - Created session to http://feeds.xbmc.org
18:41:56 T:2879449920 DEBUG: CPythonInvoker(0, /home/myth01/.xbmc/addons/service.autosubs/default.py): instantiating addon using automatically obtained id of "service.autosubs" dependent on version 2.14.0 of the xbmc.python api
18:41:56 T:2871057216 INFO: Python script stopped
18:41:56 T:2871057216 DEBUG: Thread LanguageInvoker 2871057216 terminating
18:41:56 T:2996303680 DEBUG: UPower: Received an unknown signal NameAcquired
18:41:56 T:2848979776 DEBUG: CPythonInvoker(2, /home/myth01/.xbmc/addons/script.module.simple.downloader/default.py): setting the Python path to /home/myth01/.xbmc/addons/script.module.simple.downloader:/home/myth01/.xbmc/addons/script.module.t0mm0.common/lib:/home/myth01/.xbmc/addons/script.module.simplejson/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/myth01/.xbmc/addons/script.common.plugin.cache/lib:/home/myth01/.xbmc/addons/script.module.simple.downloader/lib:/home/myth01/.xbmc/addons/script.module.urlresolver/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/myth01/.xbmc/addons/script.module.parsedom/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-i386-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:41:56 T:2848979776 DEBUG: CPythonInvoker(2, /home/myth01/.xbmc/addons/script.module.simple.downloader/default.py): entering source directory /home/myth01/.xbmc/addons/script.module.simple.downloader
18:41:56 T:2832194368 DEBUG: CPythonInvoker(4, /home/myth01/.xbmc/addons/Navi-X/service.py): setting the Python path to /home/myth01/.xbmc/addons/Navi-X:/home/myth01/.xbmc/addons/script.module.t0mm0.common/lib:/home/myth01/.xbmc/addons/script.module.simplejson/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/myth01/.xbmc/addons/script.common.plugin.cache/lib:/home/myth01/.xbmc/addons/script.module.simple.downloader/lib:/home/myth01/.xbmc/addons/script.module.urlresolver/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/myth01/.xbmc/addons/script.module.parsedom/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-i386-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:41:56 T:2832194368 DEBUG: CPythonInvoker(4, /home/myth01/.xbmc/addons/Navi-X/service.py): entering source directory /home/myth01/.xbmc/addons/Navi-X
18:41:56 T:2848979776 DEBUG: CPythonInvoker(2, /home/myth01/.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
18:41:56 T:2832194368 DEBUG: CPythonInvoker(4, /home/myth01/.xbmc/addons/Navi-X/service.py): instantiating addon using automatically obtained id of "script.navi-x" dependent on version 2.1.0 of the xbmc.python api
18:41:56 T:2840587072 DEBUG: CPythonInvoker(3, /home/myth01/.xbmc/addons/script.common.plugin.cache/default.py): setting the Python path to /home/myth01/.xbmc/addons/script.common.plugin.cache:/home/myth01/.xbmc/addons/script.module.t0mm0.common/lib:/home/myth01/.xbmc/addons/script.module.simplejson/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/myth01/.xbmc/addons/script.common.plugin.cache/lib:/home/myth01/.xbmc/addons/script.module.simple.downloader/lib:/home/myth01/.xbmc/addons/script.module.urlresolver/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/myth01/.xbmc/addons/script.module.parsedom/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-i386-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:41:56 T:2840587072 DEBUG: CPythonInvoker(3, /home/myth01/.xbmc/addons/script.common.plugin.cache/default.py): entering source directory /home/myth01/.xbmc/addons/script.common.plugin.cache
18:41:56 T:2840587072 DEBUG: CPythonInvoker(3, /home/myth01/.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
18:41:56 T:2798816064 DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
18:41:56 T:2848979776 INFO: CPythonInvoker(2, /home/myth01/.xbmc/addons/script.module.simple.downloader/default.py): script successfully run
18:41:56 T:2907839296 DEBUG: Got rss feed: http://feeds.xbmc.org/xbmc
18:41:56 T:2907839296 DEBUG: RSS feed encoding: UTF-8
18:41:56 T:2907839296 DEBUG: Parsed rss feed: http://feeds.xbmc.org/xbmc
18:41:56 T:2907839296 DEBUG: CurlFile::Open(0xad5211b0) http://feeds.xbmc.org/latest_xbmc_addons
18:41:56 T:2848979776 INFO: Python script stopped
18:41:56 T:2848979776 DEBUG: Thread LanguageInvoker 2848979776 terminating
18:41:56 T:2907839296 DEBUG: Got rss feed: http://feeds.xbmc.org/latest_xbmc_addons
18:41:56 T:2907839296 DEBUG: RSS feed encoding: UTF-8
18:41:56 T:2907839296 DEBUG: Parsed rss feed: http://feeds.xbmc.org/latest_xbmc_addons
18:41:56 T:2907839296 DEBUG: CurlFile::Open(0xad5211b0) http://feeds.xbmc.org/updated_xbmc_addons
18:41:57 T:2798816064 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
18:41:57 T:2907839296 DEBUG: Got rss feed: http://feeds.xbmc.org/updated_xbmc_addons
18:41:57 T:2907839296 DEBUG: RSS feed encoding: UTF-8
18:41:57 T:2907839296 DEBUG: Parsed rss feed: http://feeds.xbmc.org/updated_xbmc_addons
18:41:57 T:2907839296 DEBUG: Thread RSSReader 2907839296 terminating
18:41:57 T:2832194368 INFO: CPythonInvoker(4, /home/myth01/.xbmc/addons/Navi-X/service.py): script successfully run
18:41:57 T:2798816064 DEBUG: RunQuery took 26 ms for 10 items query: select * from movieview ORDER BY dateAdded desc, idMovie desc LIMIT 10
18:41:57 T:2832194368 INFO: Python script stopped
18:41:57 T:2832194368 DEBUG: Thread LanguageInvoker 2832194368 terminating
18:41:57 T:2840587072 NOTICE: StorageServer Module loaded RUN
18:41:57 T:2840587072 NOTICE: StorageClient-2.5.4 Starting server
18:41:57 T:2798816064 DEBUG: RunQuery took 4 ms for 0 items query: select * from episodeview ORDER BY dateAdded desc, idEpisode desc LIMIT 10
18:41:57 T:2798816064 DEBUG: RunQuery took 3 ms for 0 items query: select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10
18:41:57 T:2798816064 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
18:41:57 T:2832194368 NOTICE: Thread JobWorker start, auto delete: true
18:42:01 T:2996303680 NOTICE: Previous line repeats 1 times.
EDIT: Hm. I cannot post the 2nd part since it gets merged with the preceeding post and cut at 65 thousand chars. Hence I guess, I'll have to wait untill someone else adds a post and then add the missing 2nd part ;-(
RE: XBMC occassionally shows BUSY label and becomes unresponsive - fritsch - 2014-11-01
xbmclogs.com
RE: XBMC occassionally shows BUSY label and becomes unresponsive - fritsch - 2014-11-01
Your asound.conf or .asoundrc is completely foobar. Remove that.
RE: XBMC occassionally shows BUSY label and becomes unresponsive - FernetMenta - 2014-11-01
You should read XBMC's piracy policy first. Don't complain about bugs when running piracy addons like Navi-X.
RE: XBMC occassionally shows BUSY label and becomes unresponsive - cogano - 2014-11-01
(2014-11-01, 15:23)FernetMenta Wrote: You should read XBMC's piracy policy first. Don't complain about bugs when running piracy addons like Navi-X.
Yeah, I am aware about that and since I do not really use it, I have disabled it today and will test a few days.
(2014-11-01, 14:56)fritsch Wrote: Your asound.conf or .asoundrc is completely foobar. Remove that.
Well, that would be a pitty: I use asound.conf to get softvol for HDMI sound output. Otherwise, users need to use extra remote for volume adjustments...
Code: # MK: it enables dmix on digital output. (Arch Wiki)
pcm.dsp {
type plug
slave.pcm "dmix"
}
# MK: Create dmix type accessing the HDMI output
pcm.hdmi_dmix {
type dmix
ipc_key 1024
slave {
pcm "hw:NVidia,3"
#period_time 0
#period_size 1024
#buffer_time 0
#buffer_size 4096
#channels 2
#rate 50000
}
bindings {
0 0
1 1
}
}
# MK: convert all to stereo, 48 kHz
pcm.hdmi_formatted {
type plug
slave {
pcm hdmi_dmix
rate 48000
channels 2
}
}
# MK: allow software volume control
pcm.hdmi_complete {
type softvol
slave.pcm hdmi_formatted
control.name "HDMIsoftvol"
control.card NVidia
}
# MK: Set it as "ALSA Default" PCM
# "!" means override operation mode
pcm.!default hdmi_complete
I'll try to remove it anyway to find out it if matters.
Thanks for comments, I'll get back after a few days again.
And for the sake of completeness, here is the part part of log posted yet inline.
Code: 18:42:01 T:2996303680 DEBUG: ------ Window Deinit (Pointer.xml) ------
18:42:04 T:2996303680 INFO: LIRC Initialize: using: /dev/lircd
18:42:04 T:2996303680 DEBUG: Failed to connect to LIRC. Retry in 20s.
18:42:23 T:2996303680 DEBUG: SECTION:UnloadDelayed(DLL: libcrystalhd.so.3)
18:42:23 T:2996303680 DEBUG: Unloading: libcrystalhd.so.3
18:42:24 T:2996303680 INFO: LIRC Initialize: using: /dev/lircd
18:42:24 T:2996303680 DEBUG: Failed to connect to LIRC. Retry in 40s.
18:42:25 T:2996303680 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-i486-linux.so)
18:42:25 T:2996303680 DEBUG: Unloading: ImageLib-i486-linux.so
18:42:26 T:2740067136 NOTICE: Thread LanguageInvoker start, auto delete: false
18:42:26 T:2740067136 INFO: initializing python engine.
18:42:26 T:2740067136 DEBUG: CPythonInvoker(5, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): start processing
18:42:26 T:2740067136 NOTICE: -->Python Interpreter Initialized<--
18:42:26 T:2740067136 DEBUG: CPythonInvoker(5, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py"
18:42:26 T:2740067136 DEBUG: CPythonInvoker(5, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/myth01/.xbmc/addons/service.xbmc.versioncheck:/home/myth01/.xbmc/addons/script.module.t0mm0.common/lib:/home/myth01/.xbmc/addons/script.module.simplejson/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/myth01/.xbmc/addons/script.common.plugin.cache/lib:/home/myth01/.xbmc/addons/script.module.simple.downloader/lib:/home/myth01/.xbmc/addons/script.module.urlresolver/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/myth01/.xbmc/addons/script.module.parsedom/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-i386-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:42:26 T:2740067136 DEBUG: CPythonInvoker(5, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): entering source directory /home/myth01/.xbmc/addons/service.xbmc.versioncheck
18:42:26 T:2740067136 DEBUG: CPythonInvoker(5, /home/myth01/.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
18:42:26 T:2740067136 DEBUG: XBMC Version Check: Version 0.1.23 started
18:42:26 T:2740067136 DEBUG: XBMC Version Check: Version installed {u'major': 13, u'tag': u'stable', u'minor': 2, u'revision': u'0f3db05'}
18:42:26 T:2740067136 DEBUG: XBMC Version Check: Version available {u'major': u'13', u'tag': u'stable', u'extrainfo': u'final', u'minor': u'2', u'revision': u'20140817-0f3db05'}
18:42:26 T:2740067136 DEBUG: XBMC Version Check: There is no newer stable available
18:42:26 T:2740067136 INFO: CPythonInvoker(5, /home/myth01/.xbmc/addons/service.xbmc.versioncheck/service.py): script successfully run
18:42:26 T:2740067136 INFO: Python script stopped
18:42:26 T:2740067136 DEBUG: Thread LanguageInvoker 2740067136 terminating
18:42:27 T:2996303680 INFO: CheckIdle - Closing session to http://feeds.xbmc.org (easy=0xa9f34d80, multi=0xa9f00908)
18:42:27 T:2832194368 DEBUG: Thread JobWorker 2832194368 terminating (autodelete)
18:42:27 T:2848979776 DEBUG: Thread JobWorker 2848979776 terminating (autodelete)
18:42:27 T:2798816064 DEBUG: Thread JobWorker 2798816064 terminating (autodelete)
18:42:53 T:2996303680 DEBUG: UPower: Received an unknown signal DeviceChanged
18:43:04 T:2996303680 DEBUG: Previous line repeats 1 times.
18:43:04 T:2996303680 INFO: LIRC Initialize: using: /dev/lircd
18:43:04 T:2996303680 DEBUG: Failed to connect to LIRC. Giving up.
18:43:53 T:2996303680 DEBUG: UPower: Received an unknown signal DeviceChanged
18:43:56 T:2996303680 DEBUG: Previous line repeats 1 times.
18:43:56 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc
18:43:56 T:2996303680 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated
18:43:56 T:2996303680 DEBUG: ------ Window Init () ------
18:44:35 T:2996303680 DEBUG: Keyboard: scancode: 0x68, sym: 0x010f, unicode: 0x000d, modifier: 0x1000
18:44:35 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnScreensaverDeactivated from xbmc
18:44:35 T:2996303680 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverDeactivated
18:44:35 T:2996303680 DEBUG: OnKey: enter (0xf065) pressed, screen saver/dpms woken up
18:44:36 T:2996303680 DEBUG: ------ Window Deinit () ------
18:44:53 T:2996303680 DEBUG: UPower: Received an unknown signal DeviceChanged
18:46:35 T:2996303680 DEBUG: Previous line repeats 3 times.
18:46:35 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc
18:46:35 T:2996303680 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated
18:46:35 T:2996303680 DEBUG: ------ Window Init () ------
18:46:54 T:2996303680 DEBUG: UPower: Received an unknown signal DeviceChanged
18:49:35 T:2996303680 DEBUG: Previous line repeats 5 times.
18:49:35 T:2996303680 DEBUG: ------ Window Init (DialogBusy.xml) ------
18:49:53 T:2996303680 DEBUG: UPower: Received an unknown signal DeviceChanged
18:51:10 T:2996303680 DEBUG: Previous line repeats 3 times.
18:51:10 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc
18:51:10 T:2996303680 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnQuit
18:51:10 T:2996303680 NOTICE: Storing total System Uptime
18:51:10 T:2748459840 DEBUG: Thread AlarmClock 2748459840 terminating
18:51:10 T:2996303680 NOTICE: Saving settings
18:51:10 T:2996303680 NOTICE: stop all
18:51:10 T:2996303680 NOTICE: stop player
18:51:10 T:2996303680 INFO: stopping PVRManager
18:51:10 T:2996303680 DEBUG: NetworkMessage - Signaling network services to stop
18:51:10 T:2996303680 NOTICE: ES: Stopping event server
18:51:10 T:2996303680 DEBUG: NetworkMessage - Waiting for network services to stop
18:51:10 T:2996303680 NOTICE: Webserver: Stopping...
18:51:10 T:2996303680 NOTICE: WebServer: Stopped the webserver
18:51:10 T:2996303680 NOTICE: Webserver: Stopped...
18:51:11 T:2756852544 DEBUG: Thread TCPServer 2756852544 terminating
18:51:11 T:2765245248 NOTICE: ES: UDP Event server stopped
18:51:11 T:2765245248 DEBUG: Thread EventServer 2765245248 terminating
18:51:11 T:2996303680 NOTICE: stop dvd detect media
18:51:11 T:2906651456 DEBUG: Thread PeripBusUSBUdev 2906651456 terminating
18:51:11 T:2996303680 NOTICE: stop sap announcement listener
18:51:11 T:2916461376 DEBUG: Thread PeripBusCEC 2916461376 terminating
18:51:11 T:2996303680 NOTICE: clean cached files!
18:51:11 T:2996303680 NOTICE: unload skin
18:51:11 T:2996303680 INFO: Unloading old skin ...
18:51:11 T:2996303680 DEBUG: ------ Window Deinit () ------
18:51:11 T:2996303680 DEBUG: Previous line repeats 1 times.
18:51:11 T:2996303680 DEBUG: ------ Window Deinit (Home.xml) ------
18:51:11 T:2996303680 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:51:11 T:2996303680 DEBUG: Cleanup - Closed bundle
18:51:11 T:2996303680 DEBUG: ADDON: Stopping service addons.
18:51:11 T:2879449920 INFO: CPythonInvoker(0, /home/myth01/.xbmc/addons/service.autosubs/default.py): script successfully run
18:51:11 T:2996303680 DEBUG: CPythonInvoker(0, /home/myth01/.xbmc/addons/service.autosubs/default.py): script termination took 89ms
18:51:11 T:2879449920 INFO: Python script interrupted by user
18:51:11 T:2840587072 NOTICE: StorageServer-2.5.4 Closed down
18:51:11 T:2840587072 INFO: CPythonInvoker(3, /home/myth01/.xbmc/addons/script.common.plugin.cache/default.py): script successfully run
18:51:11 T:2996303680 DEBUG: CPythonInvoker(3, /home/myth01/.xbmc/addons/script.common.plugin.cache/default.py): script termination took 253ms
18:51:11 T:2879449920 DEBUG: Thread LanguageInvoker 2879449920 terminating
18:51:11 T:2840587072 INFO: Python script interrupted by user
18:51:11 T:2840587072 DEBUG: Thread LanguageInvoker 2840587072 terminating
18:51:11 T:2861546304 DEBUG: Thread ActiveAE 2861546304 terminating
18:51:11 T:2926934848 DEBUG: Thread AESink 2926934848 terminating
18:51:11 T:2996303680 NOTICE: stopped
18:51:11 T:2996303680 NOTICE: destroy
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.t0mm0.common has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in service.autosubs has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in weather.yahoo has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.fishbmc has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmchub has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.simple.downloader has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.csfd.cz has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in service.subtitles.opensubtitles has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.urlresolver has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.movieposterdb.com has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.navi-x has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in webinterface.XWMM has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in pvr.mythtv.cmyth has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.debug has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been uninstalled.'
18:51:11 T:2996303680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been uninstalled.'
18:51:11 T:2996303680 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0xa6aa980 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
18:51:11 T:2996303680 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0xa64d210 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
18:51:11 T:2996303680 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0xa6336d8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
18:51:11 T:2996303680 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0xa62d7e8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
18:51:11 T:2996303680 NOTICE: closing down remote control service
18:51:11 T:2996303680 NOTICE: unload sections
18:51:11 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:51:11 T:2996303680 DEBUG: Previous line repeats 1 times.
18:51:11 T:2996303680 NOTICE: special://profile/ is mapped to: special://masterprofile/
18:51:11 T:2996303680 DEBUG: object 0 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 1 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 2 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 3 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 4 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 5 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 6 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 7 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 8 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 9 --> 0 instances
18:51:11 T:2996303680 NOTICE: destroy
18:51:11 T:2996303680 WARNING: Attempted to remove window 10013 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10014 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10015 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10016 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10017 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10018 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10019 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10021 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10107 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10115 from the window manager when it didn't exist
18:51:11 T:2996303680 WARNING: Attempted to remove window 10104 from the window manager when it didn't exist
18:51:11 T:2996303680 NOTICE: closing down remote control service
18:51:11 T:2996303680 NOTICE: unload sections
18:51:11 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:51:11 T:2996303680 DEBUG: Previous line repeats 1 times.
18:51:11 T:2996303680 DEBUG: object 0 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 1 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 2 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 3 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 4 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 5 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 6 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 7 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 8 --> 0 instances
18:51:11 T:2996303680 DEBUG: object 9 --> 0 instances
18:51:11 T:2996303680 NOTICE: application stopped...
18:51:11 T:2996303680 DEBUG: PVRManager - destroyed
18:51:11 T:2996303680 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:51:11 T:2996303680 DEBUG: Previous line repeats 1 times.
18:51:11 T:2996303680 DEBUG: SECTION:UnloadDll(libcurl.so.4)
18:51:11 T:2996303680 DEBUG: Unloading: libcurl.so.4
18:51:11 T:2996303680 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/libcpluff-i486-linux.so)
18:51:11 T:2996303680 DEBUG: Unloading: libcpluff-i486-linux.so
18:51:11 T:2996303680 DEBUG: SECTION:UnloadAll(DLL: libcec.so.2)
18:51:11 T:2996303680 DEBUG: Unloading: libcec.so.2
RE: XBMC occassionally shows BUSY label and becomes unresponsive - cogano - 2014-11-04
I removed majority of plugins and /etc/asound.conf and the issue is still there.
Here is the log: http://xbmclogs.com/show.php?id=340560
XBMC started the screensaver (dimming the screen) at 09:20:43 (line 1142) and froze around 09:23:43 instead of going to suspend.
RE: XBMC occassionally shows BUSY label and becomes unresponsive - fritsch - 2014-11-04
Suspend as in S3 Mode?
RE: XBMC occassionally shows BUSY label and becomes unresponsive - cogano - 2014-11-04
Yes, S3 mode. It uses pm-utils. I checked the system logs at /var/log/pm-suspend.log and "pm suspend" is not even triggered.
Note that the above error happens about 50 % of time. When XBMC does not freeze in GUI, the suspend to RAM on idle works fine incl. subsequent resume.
Is there a way how to increase the debug verbosity as a run-time option of XBMC?
Or is the only way to recompile xbmc-bin package with debugging symbols and create a log with gdb, strace or similar tools? (I would try it, since I am really eager to get rid of the trouble. However, I would most probably not understand the debugging logs at all.)
RE: XBMC occassionally shows BUSY label and becomes unresponsive - cogano - 2015-01-11
I have just updated to KODI Helix and the bug is still there :-(
It is a real pitty there is no API to allow a custom add-on to find out if KODI is idle or even better if it is about to shutdown. If it existed, I would have run an external script to shutdown KODI (through JSON RPC), since that works reliably.
I tried to use xbmc.getGlobalIdleTime http://mirrors.xbmc.org/docs/python-docs/14.x-helix/xbmc.html#-getGlobalIdleTime but that does not work as intended. I would have to check also for player running and who knows what else.
|