Bug XBMC occassionally shows BUSY label and becomes unresponsive
#1
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?
Reply
#2
Don't cut logfiles, please. If you are running on AMD's fglrx for example - such behaviour is just normal.
First decide what functions / features you expect from a system. Then decide for the hardware. Don't waste your money on crap.
Reply
#3
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 ;-(
Reply
#4
xbmclogs.com
First decide what functions / features you expect from a system. Then decide for the hardware. Don't waste your money on crap.
Reply
#5
Your asound.conf or .asoundrc is completely foobar. Remove that.
First decide what functions / features you expect from a system. Then decide for the hardware. Don't waste your money on crap.
Reply
#6
You should read XBMC's piracy policy first. Don't complain about bugs when running piracy addons like Navi-X.
Reply
#7
(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
Reply
#8
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.
Reply
#9
Suspend as in S3 Mode?
First decide what functions / features you expect from a system. Then decide for the hardware. Don't waste your money on crap.
Reply
#10
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.)
Reply
#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...alIdleTime but that does not work as intended. I would have to check also for player running and who knows what else.
Reply

Logout Mark Read Team Forum Stats Members Help
XBMC occassionally shows BUSY label and becomes unresponsive0