rendered paste body18:15:36 T:3041535840 M:199098368 NOTICE: -----------------------------------------------------------------------
18:15:36 T:3041535840 M:198828032 NOTICE: Starting XBMC, Platform: Linux (Ubuntu 8.04.4 LTS, 2.6.24-28-generic i686). Built on Oct 13 2010 (SVN:34731)
18:15:36 T:3041535840 M:198868992 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
18:15:36 T:3041535840 M:198868992 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
18:15:36 T:3041535840 M:198868992 NOTICE: special://masterprofile/ is mapped to: /home/xbmc/.xbmc/userdata
18:15:36 T:3041535840 M:198868992 NOTICE: special://home/ is mapped to: /home/xbmc/.xbmc
18:15:36 T:3041535840 M:198868992 NOTICE: special://temp/ is mapped to: /home/xbmc/.xbmc/temp
18:15:36 T:3041535840 M:198868992 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
18:15:36 T:3041535840 M:198868992 NOTICE: Log File is located: /home/xbmc/.xbmc/temp/xbmc.log
18:15:36 T:3041535840 M:198868992 NOTICE: -----------------------------------------------------------------------
18:15:36 T:3041535840 M:198856704 NOTICE: Setup SDL
18:15:36 T:3041535840 M:198647808 INFO: Available videomodes (xrandr):
18:15:36 T:3041535840 M:198647808 INFO: Number of connected outputs: 1
18:15:36 T:3041535840 M:198647808 INFO: Output 'default' has 29 modes
18:15:36 T:3041535840 M:198647808 INFO: ID:0x15f Name:1280x720 Refresh:60.000000 Width:1280 Height:720
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x160 Name:1280x720 Refresh:50.000000 Width:1280 Height:720
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x161 Name:832x624 Refresh:75.000000 Width:832 Height:624
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x162 Name:800x600 Refresh:75.000000 Width:800 Height:600
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x163 Name:800x600 Refresh:72.000000 Width:800 Height:600
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x164 Name:800x600 Refresh:60.000000 Width:800 Height:600
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x165 Name:800x600 Refresh:56.000000 Width:800 Height:600
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x166 Name:720x576 Refresh:50.000000 Width:720 Height:576
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x167 Name:720x480 Refresh:60.000000 Width:720 Height:480
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x168 Name:720x450 Refresh:121.000000 Width:720 Height:450
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x169 Name:640x480 Refresh:120.000000 Width:640 Height:480
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x16a Name:640x480 Refresh:75.000000 Width:640 Height:480
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x16b Name:640x480 Refresh:73.000000 Width:640 Height:480
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x16c Name:640x480 Refresh:60.000000 Width:640 Height:480
18:15:36 T:3041535840 M:198647808 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198647808 INFO: ID:0x16d Name:640x400 Refresh:120.000000 Width:640 Height:400
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x16e Name:640x384 Refresh:120.000000 Width:640 Height:384
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x16f Name:640x360 Refresh:70.000000 Width:640 Height:360
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x170 Name:576x384 Refresh:110.000000 Width:576 Height:384
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x171 Name:512x384 Refresh:150.000000 Width:512 Height:384
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x172 Name:512x384 Refresh:140.000000 Width:512 Height:384
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x173 Name:512x384 Refresh:120.000000 Width:512 Height:384
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x174 Name:416x312 Refresh:150.000000 Width:416 Height:312
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x175 Name:400x300 Refresh:150.000000 Width:400 Height:300
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x176 Name:400x300 Refresh:145.000000 Width:400 Height:300
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x177 Name:400x300 Refresh:121.000000 Width:400 Height:300
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x178 Name:400x300 Refresh:113.000000 Width:400 Height:300
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x179 Name:320x240 Refresh:150.000000 Width:320 Height:240
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x17a Name:320x240 Refresh:146.000000 Width:320 Height:240
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 INFO: ID:0x17b Name:320x240 Refresh:120.000000 Width:320 Height:240
18:15:36 T:3041535840 M:198586368 INFO: Pixel Ratio: 1.000000
18:15:36 T:3041535840 M:198586368 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
18:15:36 T:3041535840 M:198586368 DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.FileNotFound - Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
18:15:36 T:3041535840 M:198586368 DEBUG: DBus: Creating message to org.freedesktop.UPower on /org/freedesktop/UPower with interface org.freedesktop.UPower and method EnumerateDevices
18:15:36 T:3041535840 M:198586368 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
18:15:36 T:3041535840 M:198586368 DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.FileNotFound - Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
18:15:36 T:3041535840 M:198586368 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
18:15:36 T:3041535840 M:198586368 NOTICE: load settings...
18:15:36 T:3041535840 M:198586368 DEBUG: SECTION:LoadDLL(libcrystalhd.so.3)
18:15:36 T:3041535840 M:198582272 DEBUG: Loading: libcrystalhd.so.3
18:15:37 T:3041535840 M:130023424 DEBUG: CrystalHD(new API): device opened
18:15:37 T:3041535840 M:198234112 DEBUG: CrystalHD: device closed
18:15:37 T:3041535840 M:198295552 NOTICE: special://profile/ is mapped to: special://masterprofile/
18:15:37 T:3041535840 M:198295552 NOTICE: loading special://masterprofile/guisettings.xml
18:15:37 T:3041535840 M:198250496 NOTICE: Getting hardware information now...
18:15:37 T:3041535840 M:198250496 INFO: Using digital output
18:15:37 T:3041535840 M:198250496 INFO: AC3 pass through is enabled
18:15:37 T:3041535840 M:198250496 INFO: DTS pass through is enabled
18:15:37 T:3041535840 M:198250496 INFO: AAC pass through is disabled
18:15:37 T:3041535840 M:198250496 INFO: MP1 pass through is disabled
18:15:37 T:3041535840 M:198250496 INFO: MP2 pass through is disabled
18:15:37 T:3041535840 M:198250496 INFO: MP3 pass through is disabled
18:15:37 T:3041535840 M:198250496 NOTICE: Checking resolution 12
18:15:37 T:3041535840 M:198279168 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/rtmp/mms/udp
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: flv/aacp/sdp
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdfile
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
18:15:37 T:3041535840 M:198197248 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
18:15:37 T:3041535840 M:198197248 NOTICE: Loaded playercorefactory configuration
18:15:37 T:3041535840 M:198197248 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
18:15:37 T:3041535840 M:198197248 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
18:15:37 T:3041535840 M:198197248 NOTICE: Loaded advancedsettings.xml from special://profile/advancedsettings.xml
18:15:37 T:3041535840 M:198197248 NOTICE: Contents of special://profile/advancedsettings.xml are...
<advancedsettings>
<gputempcommand>echo "$(nvidia-settings -tq gpuCoreTemp) C"</gputempcommand>
<bginfoloadermaxthreads>5</bginfoloadermaxthreads>
<videodatabase>
<type>mysql</type>
<host>192.168.0.3</host>
<port>3306</port>
<user>xbmc</user>
<pass>xbmc</pass>
<name>xbmc_video</name>
</videodatabase>
<useddsfanart>true</useddsfanart>
</advancedsettings>
18:15:37 T:3041535840 M:198197248 WARNING: VIDEO database configuration is experimental.
18:15:37 T:3041535840 M:198197248 NOTICE: Getting hardware information now...
18:15:37 T:3041535840 M:198197248 INFO: Using digital output
18:15:37 T:3041535840 M:198197248 INFO: AC3 pass through is enabled
18:15:37 T:3041535840 M:198197248 INFO: DTS pass through is enabled
18:15:37 T:3041535840 M:198197248 INFO: AAC pass through is disabled
18:15:37 T:3041535840 M:198197248 INFO: MP1 pass through is disabled
18:15:37 T:3041535840 M:198197248 INFO: MP2 pass through is disabled
18:15:37 T:3041535840 M:198197248 INFO: MP3 pass through is disabled
18:15:37 T:3041535840 M:198197248 NOTICE: Checking resolution 12
18:15:37 T:3041535840 M:198197248 NOTICE: Default DVD Player: dvdplayer
18:15:37 T:3041535840 M:198197248 NOTICE: Default Video Player: dvdplayer
18:15:37 T:3041535840 M:198197248 NOTICE: Default Audio Player: paplayer
18:15:37 T:3041535840 M:198197248 NOTICE: Loading media sources from special://masterprofile/sources.xml
18:15:37 T:3041535840 M:198225920 DEBUG: Setting <default> source to : Music
18:15:37 T:3041535840 M:198225920 INFO: creating subdirectories
18:15:37 T:3041535840 M:198225920 INFO: userdata folder: special://masterprofile/
18:15:37 T:3041535840 M:198225920 INFO: recording folder:
18:15:37 T:3041535840 M:198225920 INFO: screenshots folder:
18:15:37 T:3041535840 M:198225920 INFO: thumbnails folder: special://masterprofile/Thumbnails
18:15:37 T:3041535840 M:198254592 INFO: load language info file: special://xbmc/language/English/langinfo.xml
18:15:37 T:3041535840 M:198221824 INFO: load language file:special://xbmc/language/English/strings.xml
18:15:38 T:3041535840 M:197476352 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-i486-linux.so)
18:15:38 T:3041535840 M:197476352 DEBUG: Loading: /usr/lib/xbmc/system/libcpluff-i486-linux.so
18:15:38 T:3041535840 M:197304320 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/xbmc/addons: No such file or directory'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in skin.darklegacy has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.albums.allmusic.com has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in script.favourites has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.allmusic.com has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in skin.alaska.revisited.mod has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in script.logo-downloader has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in script.recentlyadded has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in skin.alaska has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.slideshow has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in visualization.goom has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in script.rss.editor has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in weather.xbmc.builtin has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in script.module.pysqlite has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.mtv.com has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in visualization.itunes has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
18:15:38 T:3041535840 M:197304320 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
18:15:38 T:3041535840 M:196976640 DEBUG: CKeyboardStat::Initialize - XKb symbols pc_us
18:15:38 T:3041535840 M:197017600 INFO: LIRC Initialize: using: /dev/lircd
18:15:38 T:3041535840 M:197017600 INFO: LIRC Initialize: connect failed: No such file or directory
18:15:38 T:3041535840 M:197017600 DEBUG: Failed to connect to LIRC. Retry in 10s.
18:15:38 T:3041535840 M:197017600 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output default --mode 0x15f
18:15:39 T:3041535840 M:194297856 NOTICE: Using visual 0x4e
18:15:39 T:3041535840 M:193622016 INFO: GL: Maximum texture width: 4096
18:15:39 T:3041535840 M:193622016 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-i486-linux.so)
18:15:39 T:3041535840 M:193622016 DEBUG: Loading: /usr/lib/xbmc/system/ImageLib-i486-linux.so
18:15:39 T:3041535840 M:192917504 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_texture_from_pixmap GLX_ARB_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_ARB_get_proc_address
18:15:39 T:3041535840 M:192958464 NOTICE: GL_VENDOR = NVIDIA Corporation
18:15:39 T:3041535840 M:192958464 NOTICE: GL_RENDERER = GeForce Go 7300/PCI/SSE2
18:15:39 T:3041535840 M:192958464 NOTICE: GL_VERSION = 2.1.2 NVIDIA 169.12
18:15:39 T:3041535840 M:192958464 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.20 NVIDIA via Cg compiler
18:15:39 T:3041535840 M:192958464 NOTICE: GL_EXTENSIONS = GL_ARB_color_buffer_float GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_half_float_pixel GL_ARB_imaging GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_shadow GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirrored_repeat GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_transpose_matrix GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader 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_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_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_framebuffer_object GL_EXT_gpu_program_parameters GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_specular_color GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D 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_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_sRGB GL_EXT_timer_query GL_EXT_vertex_array GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KTX_buffer_region GL_NV_blend_square GL_NV_copy_depth_to_color GL_NV_depth_clamp 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_half_float GL_NV_light_max_exponent GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_texgen_reflection GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_vertex_array_range GL_NV_vertex_array_range2 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_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
18:15:39 T:3041535840 M:192958464 ERROR: GLX: Same window as before, refreshing context
18:15:39 T:3041535840 M:192958464 INFO: GL: Maximum texture width: 4096
18:15:39 T:3041535840 M:192958464 INFO: load default splash image: /usr/share/xbmc/media/Splash.png
18:15:39 T:3041535840 M:189591552 INFO: load keymapping
18:15:39 T:3041535840 M:189657088 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
18:15:39 T:3041535840 M:189657088 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
18:15:39 T:3041535840 M:189685760 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
18:15:39 T:3041535840 M:189644800 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
18:15:39 T:3041535840 M:189652992 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
18:15:39 T:3041535840 M:189652992 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
18:15:39 T:3041535840 M:189681664 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
18:15:39 T:3041535840 M:189640704 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
18:15:39 T:3041535840 M:189648896 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
18:15:39 T:3041535840 M:189677568 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
18:15:39 T:3041535840 M:189677568 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
18:15:39 T:3041535840 M:189657088 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
18:15:39 T:3041535840 M:189644800 INFO: Loading special://xbmc/system/keymaps/remote.xml
18:15:39 T:3041535840 M:189673472 INFO: Loading special://xbmc/system/Lircmap.xml
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'mceusb'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'XboxDVDDongle'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'anysee'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'iMON-PAD'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'MCE_via_iMON'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'TwinHanRemote'
18:15:39 T:3041535840 M:189652992 INFO: * Adding remote mapping for device 'linux-input-layer'
18:15:39 T:3041535840 M:189591552 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
18:15:39 T:3041535840 M:189591552 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
18:15:39 T:3041535840 M:189591552 INFO: GUI format 1280x720 1280x720 @ 60.00 - Full Screen
18:15:39 T:3041535840 M:189591552 DEBUG: Selected HAL as storage provider
18:15:39 T:3041535840 M:189591552 INFO: HAL: Starting initializing
18:15:39 T:3041535840 M:189591552 ERROR: DBus: Could not get system bus: Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
18:15:39 T:3041535840 M:189591552 ERROR: HAL: no Hal context
18:15:39 T:3041535840 M:189591552 NOTICE: start dvd mediatype detection
18:15:39 T:3019107216 M:189571072 DEBUG: thread start, auto delete: 0
18:15:39 T:3019107216 M:189571072 DEBUG: Compiled with libcdio Version 0.78
18:15:39 T:3019107216 M:189571072 DEBUG: Thread 3019107216 terminating
18:15:39 T:3041535840 M:189571072 NOTICE: initializing playlistplayer
18:15:39 T:3041535840 M:189571072 NOTICE: DONE initializing playlistplayer
18:15:39 T:3041535840 M:189571072 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
18:15:39 T:3041535840 M:189616128 INFO: delete old skin...
18:15:39 T:3041535840 M:189247488 DEBUG: ------ Window Deinit (Pointer.xml) ------
18:15:39 T:3041535840 M:189247488 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
18:15:39 T:3041535840 M:189247488 INFO: load skin from:/home/xbmc/.xbmc/addons/skin.alaska
18:15:39 T:3041535840 M:189247488 INFO: Default 4:3 resolution directory is /home/xbmc/.xbmc/addons/skin.alaska/720p
18:15:39 T:3041535840 M:189247488 INFO: Default 16:9 resolution directory is /home/xbmc/.xbmc/addons/skin.alaska/720p
18:15:39 T:3041535840 M:189276160 INFO: Loading skin includes from /home/xbmc/.xbmc/addons/skin.alaska/720p/Includes.xml
18:15:39 T:3041535840 M:187457536 INFO: load fonts for skin...
18:15:39 T:3041535840 M:187486208 INFO: Loading colors from /home/xbmc/.xbmc/addons/skin.alaska/colors/Blue Mosaic.xml
18:15:39 T:3041535840 M:187514880 INFO: Loading fonts from /home/xbmc/.xbmc/addons/skin.alaska/720p/Font.xml
18:15:40 T:3041535840 M:174256128 INFO: load new skin...
18:15:40 T:3041535840 M:174256128 INFO: Loading skin file: Home.xml
18:15:40 T:3041535840 M:172863488 DEBUG: Load Home.xml: 126.70ms
18:15:40 T:3041535840 M:172863488 INFO: Loading user windows, path /home/xbmc/.xbmc/addons/skin.alaska/720p
18:15:40 T:3041535840 M:172716032 DEBUG: Load Skin XML: 161.30ms
18:15:40 T:3041535840 M:172716032 INFO: initialize new skin...
18:15:40 T:3041535840 M:172716032 INFO: Loading skin file: Pointer.xml
18:15:40 T:3041535840 M:172695552 DEBUG: Load Pointer.xml: 7.32ms
18:15:40 T:3041535840 M:167731200 DEBUG: Alloc resources: 82.31ms (7.52 ms skin load)
18:15:40 T:3041535840 M:167759872 INFO: Loading skin file: DialogVolumeBar.xml
18:15:40 T:3041535840 M:167821312 DEBUG: Load DialogVolumeBar.xml: 6.57ms
18:15:40 T:3041535840 M:167821312 DEBUG: Alloc resources: 7.28ms (6.87 ms skin load)
18:15:40 T:3041535840 M:167821312 INFO: Loading skin file: DialogSeekBar.xml
18:15:40 T:3041535840 M:167821312 WARNING: CreateFile, successfuly opened </home/xbmc/.xbmc/addons/skin.alaska/720p/DialogSeekbar.xml> instead of </home/xbmc/.xbmc/addons/skin.alaska/720p/DialogSeekBar.xml>
18:15:40 T:3041535840 M:167821312 DEBUG: Load DialogSeekBar.xml: 14.23ms
18:15:40 T:3041535840 M:167829504 DEBUG: Alloc resources: 30.50ms (14.63 ms skin load)
18:15:40 T:3041535840 M:167829504 INFO: Loading skin file: DialogKaiToast.xml
18:15:40 T:3041535840 M:167829504 DEBUG: Load DialogKaiToast.xml: 4.57ms
18:15:40 T:3041535840 M:167829504 DEBUG: Alloc resources: 4.80ms (4.80 ms skin load)
18:15:40 T:3041535840 M:167829504 INFO: Loading skin file: DialogMuteBug.xml
18:15:40 T:3041535840 M:167829504 DEBUG: Load DialogMuteBug.xml: 3.85ms
18:15:40 T:3041535840 M:167829504 DEBUG: Alloc resources: 4.06ms (4.06 ms skin load)
18:15:40 T:3041535840 M:167829504 INFO: Loading skin file: DialogVolumeBar.xml
18:15:40 T:3041535840 M:167829504 DEBUG: Load DialogVolumeBar.xml: 5.48ms
18:15:40 T:3041535840 M:167829504 INFO: Loading skin file: DialogKaiToast.xml
18:15:40 T:3041535840 M:167829504 DEBUG: Load DialogKaiToast.xml: 4.13ms
18:15:40 T:3041535840 M:167829504 INFO: Loading skin file: DialogSeekBar.xml
18:15:40 T:3041535840 M:167829504 WARNING: CreateFile, successfuly opened </home/xbmc/.xbmc/addons/skin.alaska/720p/DialogSeekbar.xml> instead of </home/xbmc/.xbmc/addons/skin.alaska/720p/DialogSeekBar.xml>
18:15:40 T:3041535840 M:167829504 DEBUG: Load DialogSeekBar.xml: 13.35ms
18:15:40 T:3041535840 M:167829504 INFO: Loading skin file: DialogBusy.xml
18:15:40 T:3041535840 M:167829504 DEBUG: Load DialogBusy.xml: 2.95ms
18:15:40 T:3041535840 M:167796736 INFO: skin loaded...
18:15:40 T:3041535840 M:167796736 DEBUG: Activating window ID: 12999
18:15:40 T:3041535840 M:167796736 DEBUG: Checking if window ID 12999 is locked.
18:15:40 T:3041535840 M:167796736 DEBUG: ------ Window Init (Startup.xml) ------
18:15:40 T:3041535840 M:167796736 INFO: Loading skin file: Startup.xml
18:15:40 T:3041535840 M:167796736 DEBUG: Load Startup.xml: 25.07ms
18:15:40 T:3041535840 M:167796736 DEBUG: Alloc resources: 25.26ms (25.26 ms skin load)
18:15:40 T:3041535840 M:167796736 INFO: removing tempfiles
18:15:40 T:3041535840 M:167796736 NOTICE: initialize done
18:15:40 T:3041535840 M:167796736 NOTICE: Running the application...
18:15:40 T:3041535840 M:167825408 DEBUG: ExecuteXBMCAction : Translating SetFocus(11)
18:15:40 T:3041535840 M:167825408 DEBUG: ExecuteXBMCAction : To SetFocus(11)
18:15:40 T:3041535840 M:167825408 DEBUG: Process - no profile autoexec.py (/home/xbmc/.xbmc/userdata/autoexec.py) found, skipping
18:15:40 T:3041535840 M:167825408 DEBUG: NetworkMessage - Starting network services
18:15:40 T:3041535840 M:167825408 NOTICE: Webserver: Starting...
18:15:40 T:3041535840 M:167825408 NOTICE: WebServer: Started the webserver
18:15:40 T:3041535840 M:167825408 ERROR: CZeroconfAvahi::CZeroconfAvahi(): Could not create client
18:15:40 T:3041535840 M:167804928 NOTICE: ES: Starting event server
18:15:40 T:2515618704 M:167804928 DEBUG: thread start, auto delete: 0
18:15:40 T:2515618704 M:167804928 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
18:15:40 T:3041535840 M:167804928 NOTICE: DS: Starting dbus server
18:15:40 T:2515618704 M:167682048 NOTICE: UDP: Listening on port 9777
18:15:40 T:3041535840 M:167743488 ERROR: DS: Failed to connect to the D-Bus session daemon: Failed to execute dbus-launch to autolaunch D-Bus session
18:15:40 T:2507226000 M:167784448 DEBUG: thread start, auto delete: 0
18:15:40 T:2507226000 M:167784448 INFO: JSONRPC Server: Successfully initialized
18:15:40 T:3041535840 M:167784448 NOTICE: starting zeroconf publishing
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc type: _xbmc-jsonrpc._tcp name:XBMC JSONRPC port:9090
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webapi type: _xbmc-web._tcp name:XBMC HTTP API port:8080
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webjsonrpc type: _xbmc-jsonrpc._tcp name:XBMC JSONRPC port:8080
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:XBMC Web Server port:8080
18:15:40 T:3041535840 M:167784448 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
18:15:40 T:3041535840 M:167784448 DEBUG: CLastfmScrobbler: Clearing session.
18:15:40 T:3041535840 M:167784448 DEBUG: CLibrefmScrobbler: Clearing session.
18:15:40 T:3041535840 M:168706048 DEBUG: ExecuteXBMCAction : Translating XBMC.RunScript(script.recentlyadded,limit=5&albums=True&unplayed=True&totals=True)
18:15:40 T:3041535840 M:168747008 DEBUG: ExecuteXBMCAction : To XBMC.RunScript(script.recentlyadded,limit=5&albums=True&unplayed=True&totals=True)
18:15:40 T:3041535840 M:168771584 INFO: initializing python engine.
18:15:40 T:3041535840 M:168771584 DEBUG: Loading: /usr/lib/xbmc/system/python/python24-i486-linux.so
18:15:40 T:3041535840 M:168718336 DEBUG: Python wrapper library linked with internal Python library
18:15:41 T:3041535840 M:167645184 DEBUG: GetZipList - Processing zip://%2fusr%2flib%2fxbmc%2fsystem%2fpython%2fpython24%2ezip/sitecustomize
18:15:41 T:3041535840 M:166649856 DEBUG: new python thread created. id=1
18:15:41 T:2494610320 M:166649856 DEBUG: thread start, auto delete: 0
18:15:41 T:2494610320 M:166649856 DEBUG: Python thread: start processing
18:15:42 T:3041535840 M:166649856 DEBUG: ExecuteXBMCAction : Translating SetFocus(12)
18:15:42 T:3041535840 M:166649856 DEBUG: ExecuteXBMCAction : To SetFocus(12)
18:15:42 T:2485123984 M:166649856 DEBUG: thread start, auto delete: 1
18:15:42 T:3041535840 M:166649856 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
18:15:42 T:3041535840 M:166649856 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
18:15:42 T:3041535840 M:166649856 DEBUG: Activating window ID: 10000
18:15:42 T:3041535840 M:166649856 DEBUG: Checking if window ID 10000 is locked.
18:15:42 T:3041535840 M:166649856 DEBUG: ------ Window Deinit (Startup.xml) ------
18:15:42 T:3041535840 M:166649856 DEBUG: ------ Window Init (Home.xml) ------
18:15:42 T:2485123984 M:166649856 INFO: WEATHER: Downloading weather
18:15:42 T:2485123984 M:166649856 DEBUG: SECTION:LoadDLL(libcurl-gnutls.so.4)
18:15:42 T:2485123984 M:166649856 DEBUG: Loading: libcurl-gnutls.so.4
18:15:42 T:2485123984 M:166637568 DEBUG: FileCurl::Open(0x941fe148) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
18:15:42 T:2485123984 M:166637568 INFO: easy_aquire - Created session to http://xoap.weather.com
18:15:42 T:3041535840 M:166453248 DEBUG: Alloc resources: 82.34ms (0.00 ms skin load)
18:15:42 T:2494610320 M:165601280 NOTICE: -->Python Interpreter Initialized<--
18:15:42 T:2494610320 M:165601280 DEBUG: Process - The source file to load is /home/xbmc/.xbmc/addons/script.recentlyadded/default.py
18:15:42 T:2494610320 M:165601280 DEBUG: Process - Setting the Python path to /home/xbmc/.xbmc/addons/script.recentlyadded:/usr/lib/xbmc/addons/script.module.pil/lib:/usr/lib/xbmc/addons/script.module.pysqlite/lib:/usr/lib/xbmc/system/python/python24.zip:/usr/share/xbmc/system/python/lib/python24.zip:/usr/share/xbmc/system/python/lib/python2.4/:/usr/share/xbmc/system/python/lib/python2.4/plat-linux2:/usr/share/xbmc/system/python/lib/python2.4/lib-tk:/usr/share/xbmc/system/python/lib/python2.4/lib-dynload
18:15:42 T:2494610320 M:165601280 DEBUG: Process - Entering source directory /home/xbmc/.xbmc/addons/script.recentlyadded
18:15:42 T:2494610320 M:159084544 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/strop.so. flags: 2
18:15:42 T:2494610320 M:158830592 DEBUG: Loading: /home/xbmc/.xbmc/temp/strop.so
18:15:42 T:2494610320 M:158830592 DEBUG: RegisterExtensionLib, adding strop.so (0x90c9888)
18:15:42 T:2494610320 M:158830592 DEBUG: xbp_dlsym - load symbol initstrop
18:15:42 T:2494610320 M:158502912 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/_socket.so. flags: 2
18:15:42 T:2494610320 M:158609408 DEBUG: Loading: /home/xbmc/.xbmc/temp/_socket.so
18:15:42 T:2494610320 M:158609408 DEBUG: RegisterExtensionLib, adding _socket.so (0xa445468)
18:15:42 T:2494610320 M:158609408 DEBUG: xbp_dlsym - load symbol init_socket
18:15:42 T:2494610320 M:158363648 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/_ssl.so. flags: 2
18:15:42 T:2494610320 M:157962240 DEBUG: Loading: /home/xbmc/.xbmc/temp/_ssl.so
18:15:42 T:2494610320 M:157962240 DEBUG: RegisterExtensionLib, adding _ssl.so (0xa4e48c8)
18:15:42 T:2494610320 M:157962240 DEBUG: xbp_dlsym - load symbol init_ssl
18:15:42 T:2494610320 M:156147712 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/time.so. flags: 2
18:15:42 T:2494610320 M:156635136 DEBUG: Loading: /home/xbmc/.xbmc/temp/time.so
18:15:42 T:2494610320 M:156635136 DEBUG: RegisterExtensionLib, adding time.so (0x9141908)
18:15:42 T:2494610320 M:156635136 DEBUG: xbp_dlsym - load symbol inittime
18:15:43 T:2494610320 M:155852800 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/math.so. flags: 2
18:15:43 T:2494610320 M:155856896 DEBUG: Loading: /home/xbmc/.xbmc/temp/math.so
18:15:43 T:2494610320 M:155856896 DEBUG: RegisterExtensionLib, adding math.so (0xa2e5160)
18:15:43 T:2494610320 M:155856896 DEBUG: xbp_dlsym - load symbol initmath
18:15:43 T:2494610320 M:155734016 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/binascii.so. flags: 2
18:15:43 T:2494610320 M:155103232 DEBUG: Loading: /home/xbmc/.xbmc/temp/binascii.so
18:15:43 T:2494610320 M:155103232 DEBUG: RegisterExtensionLib, adding binascii.so (0xa40d9d8)
18:15:43 T:2494610320 M:155103232 DEBUG: xbp_dlsym - load symbol initbinascii
18:15:43 T:2494610320 M:155451392 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/_random.so. flags: 2
18:15:43 T:2494610320 M:155475968 DEBUG: Loading: /home/xbmc/.xbmc/temp/_random.so
18:15:43 T:2494610320 M:155475968 DEBUG: RegisterExtensionLib, adding _random.so (0xa3d6e90)
18:15:43 T:2494610320 M:155475968 DEBUG: xbp_dlsym - load symbol init_random
18:15:43 T:3041535840 M:152674304 DEBUG: ExecuteXBMCAction : Translating SetFocus(300)
18:15:43 T:3041535840 M:152674304 DEBUG: ExecuteXBMCAction : To SetFocus(300)
18:15:43 T:3041535840 M:152674304 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:15:43 T:3041535840 M:152674304 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat
18:15:43 T:3041535840 M:152674304 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; OpenRecord;<record>
18:15:43 T:3041535840 M:152674304 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; CloseRecord;</record>
18:15:43 T:2494610320 M:152551424 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/datetime.so. flags: 2
18:15:43 T:2494610320 M:152702976 DEBUG: Loading: /home/xbmc/.xbmc/temp/datetime.so
18:15:43 T:2494610320 M:152702976 DEBUG: RegisterExtensionLib, adding datetime.so (0xa44ad80)
18:15:43 T:2494610320 M:152702976 DEBUG: xbp_dlsym - load symbol initdatetime
18:15:43 T:3041535840 M:152702976 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select count(1), count(playCount), movieview.* from movieview group by lastPlayed
18:15:44 T:3041535840 M:151945216 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select count(1), count(playCount) from musicvideoview
18:15:44 T:3041535840 M:152313856 DEBUG: HttpApi Start command: QueryVideoDatabase paras: [not recorded]
18:15:44 T:3041535840 M:152313856 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select count(1), count(distinct strAlbum), count(distinct strArtist) from songview
18:15:44 T:3041535840 M:152264704 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from movieview where playCount isnull order by idMovie desc limit 5
18:15:44 T:3041535840 M:152305664 ERROR: SQL: Undefined MySQL error: Code (1064)
Query: select * from movieview where playCount isnull order by idMovie desc limit 5
18:15:44 T:3041535840 M:152326144 ERROR: GetArbitraryQuery failed
18:15:44 T:3041535840 M:152264704 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from episodeview where playCount isnull order by idepisode desc limit 5
18:15:44 T:3041535840 M:152305664 ERROR: SQL: Undefined MySQL error: Code (1064)
Query: select * from episodeview where playCount isnull order by idepisode desc limit 5
18:15:44 T:3041535840 M:152305664 ERROR: GetArbitraryQuery failed
18:15:44 T:3041535840 M:152305664 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select idAlbum from albumview order by idAlbum desc limit 5
18:15:44 T:3041535840 M:152305664 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select songview.* from songview where (idAlbum = 365 and lastplayed isnull) limit 1
18:15:44 T:3041535840 M:152276992 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select songview.* from songview where (idAlbum = 364 and lastplayed isnull) limit 1
18:15:44 T:3041535840 M:152276992 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select songview.* from songview where (idAlbum = 363 and lastplayed isnull) limit 1
18:15:44 T:3041535840 M:152276992 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select songview.* from songview where (idAlbum = 362 and lastplayed isnull) limit 1
18:15:44 T:3041535840 M:152276992 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select songview.* from songview where (idAlbum = 361 and lastplayed isnull) limit 1
18:15:44 T:2494610320 M:152276992 INFO: Scriptresult: Success
18:15:45 T:2494610320 M:152260608 DEBUG: xbp_dlopen loading python lib /usr/lib/xbmc/system/python/python24.zip/collections.so. flags: 2
18:15:45 T:2494610320 M:152305664 DEBUG: Loading: /home/xbmc/.xbmc/temp/collections.so
18:15:45 T:2494610320 M:152305664 DEBUG: RegisterExtensionLib, adding collections.so (0xa46b4e8)
18:15:45 T:2494610320 M:152305664 DEBUG: xbp_dlsym - load symbol initcollections
18:15:45 T:2494610320 M:152305664 INFO: Python script stopped
18:15:45 T:2494610320 M:152305664 DEBUG: Thread 2494610320 terminating
18:15:45 T:3041535840 M:152305664 DEBUG: python thread 1 destructed
18:15:47 T:3041535840 M:152338432 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:15:48 T:2485123984 M:152338432 DEBUG: FileCurl::Close(0x941fe148) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
18:15:48 T:2485123984 M:152338432 INFO: WEATHER: Weather download successful
18:15:48 T:2485123984 M:152338432 DEBUG: GetZipList - Processing zip://special%3a%2f%2fxbmc%2fmedia%2fweather%2ezip/
18:15:48 T:3041535840 M:152522752 INFO: LIRC Initialize: using: /dev/lircd
18:15:48 T:3041535840 M:152522752 DEBUG: Failed to connect to LIRC. Retry in 20s.
18:15:52 T:3041535840 M:152363008 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:15:55 T:3041535840 M:152363008 INFO: Python, unloading python24.dll because no scripts are running anymore
18:15:55 T:3041535840 M:152363008 DEBUG: UnloadExtensionLibs, clearing python extension libraries
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: strop.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: _socket.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: _ssl.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: time.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: math.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: binascii.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: _random.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: datetime.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: collections.so
18:15:55 T:3041535840 M:152363008 DEBUG: Unloading: python24-i486-linux.so
18:15:57 T:3041535840 M:152371200 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:06 T:3041535840 M:152379392 DEBUG: Previous line repeats 1 times.
18:16:06 T:3041535840 M:152379392 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoLibrary,movietitles,return)
18:16:06 T:3041535840 M:152379392 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoLibrary,movietitles,return)
18:16:06 T:3041535840 M:152379392 DEBUG: Activating window ID: 10025
18:16:06 T:3041535840 M:152379392 DEBUG: Checking if window ID 10024 is locked.
18:16:06 T:3041535840 M:152379392 DEBUG: ------ Window Deinit (Home.xml) ------
18:16:08 T:3041535840 M:152371200 DEBUG: ------ Window Init (MyVideoNav.xml) ------
18:16:08 T:3041535840 M:152371200 INFO: Loading skin file: MyVideoNav.xml
18:16:08 T:3041535840 M:152133632 WARNING: Trying to add unsupported control type 3
18:16:08 T:3041535840 M:150167552 WARNING: Previous line repeats 17 times.
18:16:08 T:3041535840 M:150167552 DEBUG: Load MyVideoNav.xml: 303.64ms
18:16:08 T:3041535840 M:150167552 DEBUG: Alloc resources: 334.97ms (307.29 ms skin load)
18:16:08 T:3041535840 M:150167552 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
18:16:08 T:3041535840 M:150167552 DEBUG: ParentPath = [videodb://1/2/]
18:16:08 T:3041535840 M:150175744 DEBUG: GetSetsNav query: SELECT sets.idSet,sets.strSet,COUNT(1) AS c,count(files.playCount) FROM sets JOIN setlinkmovie ON sets.idSet=setlinkmovie.idSet JOIN movie ON setlinkmovie.idMovie=movie.idMovie JOIN files ON files.idFile=movie.idFile GROUP BY sets.idSet HAVING c>1
18:16:08 T:3041535840 M:150175744 DEBUG: GetMoviesByWhere query: select * from movieview WHERE movieview.idMovie NOT IN (SELECT idMovie FROM setlinkmovie s1 JOIN(SELECT idSet, COUNT(1) AS c FROM setlinkmovie GROUP BY idSet HAVING c>1) s2 ON s2.idSet=s1.idSet)
18:16:08 T:3041535840 M:149069824 DEBUG: Time for actual SQL query = 74
18:16:08 T:3041535840 M:149016576 DEBUG: Time to retrieve movies from dataset = 146
18:16:08 T:3041535840 M:148922368 DEBUG: Saving fileitems [videodb://1/2/]
18:16:08 T:3041535840 M:148926464 DEBUG: -- items: 88, sort method: 25, ascending: true
18:16:08 T:2494610320 M:148926464 DEBUG: thread start, auto delete: 0
18:16:08 T:2494610320 M:147632128 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20A%2dTeam
18:16:08 T:2452093840 M:140775424 DEBUG: thread start, auto delete: 1
18:16:09 T:2494610320 M:139362304 DEBUG: Previous line repeats 2 times.
18:16:09 T:2494610320 M:139362304 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Army%20of%20Darkness
18:16:09 T:2494610320 M:139419648 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Cop%20Land
18:16:09 T:2494610320 M:139419648 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Crank
18:16:09 T:2494610320 M:139362304 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Crazies
18:16:09 T:2494610320 M:139112448 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Dawn%20Of%20The%20Dead
18:16:09 T:3041535840 M:150528000 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:09 T:3041535840 M:150589440 INFO: LIRC Initialize: using: /dev/lircd
18:16:09 T:3041535840 M:150589440 DEBUG: Failed to connect to LIRC. Retry in 40s.
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Encounters%20at%20the%20end%20of%20the%20World
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Evil%20Dead
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Evil%20Dead%202
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Exit%20through%20the%20Gift%20Shop
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Expendables
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Fourth%20Kind
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Get%20Him%20To%20The%20Greek
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Green%20Mile
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Iron%20Man%202
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/James%20Bond%20%2d%20Dr%20No
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/James%20Bond%20%2d%20From%20Russia%20With%20Love
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/James%20Bond%20%2d%20Goldfinger
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/James%20Bond%20%2d%20Thunderball
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/James%20Bond%20%2d%20You%20Only%20Live%20Twice
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Karate%20Kid
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Karate%20Kid%20Part%20II
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Kick%2dAss
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media/Movies
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Land%20Of%20The%20Dead
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media/Movies/Amelie
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Metropia
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Predators
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Road%20%282009%29
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Solitary%20Man
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Toy%20Story%203
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Waynes%20World
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/Waynes%20World%202
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20White%20Diamond
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/X%2dMen%20Origins%20%2d%20Wolverine
18:16:09 T:2494610320 M:149053440 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media2/Movies/The%20Yes%20Men%20Fix%20The%20World
18:16:09 T:2494610320 M:149053440 DEBUG: Thread 2494610320 terminating
18:16:12 T:3041535840 M:149004288 DEBUG: new file set audiostream:0
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerCoreFactory::GetPlayers(smb://ANNIHILATE/Media/Movies/The 40 Year Old Virgin.wmv)
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
18:16:13 T:3041535840 M:149004288 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
18:16:13 T:3041535840 M:147775488 NOTICE: DVDPlayer: Opening: smb://ANNIHILATE/Media/Movies/The 40 Year Old Virgin.wmv
18:16:13 T:3041535840 M:147775488 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:16:13 T:2474638224 M:147775488 DEBUG: thread start, auto delete: 0
18:16:13 T:2474638224 M:147775488 NOTICE: Creating InputStream
18:16:13 T:2474638224 M:147775488 DEBUG: CFileSMB::Open - opened Media/Movies/The 40 Year Old Virgin.wmv, fd=10000
18:16:13 T:2474638224 M:147775488 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
18:16:13 T:2474638224 M:147775488 DEBUG: CacheSubtitles: START
18:16:13 T:2474638224 M:147775488 DEBUG: CacheSubtitles: Checking for common subdirs...
18:16:13 T:2474638224 M:147775488 DEBUG: CacheSubtitles: Done (time: 12 ms)
18:16:13 T:2474638224 M:147775488 DEBUG: CacheSubtitles: Searching for subtitles...
18:16:13 T:2474638224 M:147775488 DEBUG: OpenDir - Using authentication url smb://ANNIHILATE/Media/Movies
18:16:13 T:3041535840 M:147775488 DEBUG: ------ Window Init (DialogBusy.xml) ------
18:16:13 T:3041535840 M:147775488 DEBUG: Alloc resources: 0.01ms (0.00 ms skin load)
18:16:13 T:2474638224 M:147767296 DEBUG: CacheSubtitles: Done (time: 456 ms)
18:16:13 T:2474638224 M:147767296 DEBUG: CacheSubtitles: END (total time: 470 ms)
18:16:13 T:2474638224 M:147767296 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
18:16:13 T:2474638224 M:147767296 NOTICE: Creating Demuxer
18:16:13 T:2474638224 M:147767296 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-50-i486-linux.so)
18:16:13 T:2474638224 M:147767296 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avutil-50-i486-linux.so
18:16:13 T:2474638224 M:147709952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-52-i486-linux.so)
18:16:13 T:2474638224 M:147709952 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so
18:16:13 T:2474638224 M:144302080 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-52-i486-linux.so)
18:16:13 T:2474638224 M:144302080 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avformat-52-i486-linux.so
18:16:13 T:2474638224 M:143659008 DEBUG: Open - probing detected format [asf]
18:16:13 T:2474638224 M:143659008 DEBUG: Open - av_find_stream_info starting
18:16:14 T:2474638224 M:141262848 DEBUG: ffmpeg[937FFB90]: [asf] max_analyze_duration reached
18:16:14 T:2474638224 M:141975552 DEBUG: Open - av_find_stream_info finished
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: Input #0, asf, from 'smb://ANNIHILATE/Media/Movies/The 40 Year Old Virgin.wmv':
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: Metadata:
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WMFSDKVersion : 11.0.6000.6324
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WMFSDKNeeded : 0.0.0.0000
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: VBR Peak : 300
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: IsVBR : 1
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WM/SubTitleDescription:
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WM/SubTitle :
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WM/Year : 2005
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WM/ParentalRating: G
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WM/Genre :
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: WM/TVNetworkAffiliation:
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: title : The 40 Year Old Virgin
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: author :
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: copyright :
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: comment :
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: Duration: 02:12:27.60, start: 60.000000, bitrate: 8586 kb/s
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: Stream #0.0: Audio: wmapro, 48000 Hz, 5.1, flt, 768 kb/s
18:16:14 T:2474638224 M:141975552 INFO: ffmpeg[937FFB90]: Stream #0.1: Video: vc1, yuv420p, 1280x720, 7787 kb/s, 23.98 tbr, 1k tbn, 1k tbc
18:16:14 T:2474638224 M:141975552 NOTICE: Opening video stream: 1 source: 256
18:16:14 T:2474638224 M:141975552 NOTICE: Creating video codec with codec id: 73
18:16:14 T:2474638224 M:141975552 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:yes VAAPI:no
18:16:14 T:2474638224 M:141975552 INFO: Trying Broadcom Crystal HD Decoder...
18:16:14 T:2474638224 M:141975552 DEBUG: FactoryCodec - Video: - Opening
18:16:17 T:2474638224 M: 74829824 DEBUG: CrystalHD(new API): device opened
18:16:17 T:2474638224 M: 74944512 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-0.6.1-i486-linux.so)
18:16:17 T:2474638224 M: 74944512 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so
18:16:18 T:2333391760 M: 74809344 DEBUG: thread start, auto delete: 0
18:16:18 T:2474638224 M: 74813440 INFO: DVDVideoCodecCrystalHD: Opened Broadcom Crystal HD Codec
18:16:18 T:2474638224 M: 74813440 DEBUG: FactoryCodec - Video: chd-vc1 - Opened
18:16:18 T:2333391760 M: 74813440 DEBUG: CrystalHD: Output Thread Started...
18:16:18 T:2474638224 M: 74813440 NOTICE: Creating video thread
18:16:18 T:2324999056 M: 74792960 DEBUG: thread start, auto delete: 0
18:16:18 T:2474638224 M: 74797056 NOTICE: Opening audio stream: 0 source: 256
18:16:18 T:2474638224 M: 74797056 NOTICE: Finding audio codec for: 86057
18:16:18 T:3041535840 M: 74792960 DEBUG: ------ Window Init (VideoOverlay.xml) ------
18:16:18 T:3041535840 M: 74792960 INFO: Loading skin file: VideoOverlay.xml
18:16:18 T:2474638224 M: 74792960 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opening
18:16:18 T:2474638224 M: 74792960 DEBUG: CDVDAudioCodecPassthroughFFmpeg::SetupEncoder - Setting up encoder for on the fly transcode
18:16:18 T:2474638224 M: 74424320 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:16:18 T:2474638224 M: 74117120 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:16:18 T:2474638224 M: 74117120 DEBUG: CDVDAudioCodecPassthroughFFmpeg::SetupEncoder - Ready to transcode
18:16:18 T:2474638224 M: 74117120 INFO: CDVDAudioCodecPassthroughFFmpeg::SetupMuxer - Trying to setup spdif muxer
18:16:18 T:2474638224 M: 74117120 INFO: CDVDAudioCodecPassthroughFFmpeg::SetupMuxer - spdif muxer ready
18:16:18 T:2474638224 M: 74117120 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opened
18:16:18 T:2474638224 M: 74117120 NOTICE: Creating audio thread
18:16:18 T:2316606352 M: 74117120 DEBUG: thread start, auto delete: 0
18:16:18 T:2474638224 M: 74153984 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://ANNIHILATE/Media/Movies/The 40 Year Old Virgin.wmv
18:16:18 T:2316606352 M: 74113024 NOTICE: running thread: CDVDPlayerAudio::Process()
18:16:18 T:2474638224 M: 74125312 DEBUG: CDVDPlayer::SetCaching - caching state 2
18:16:18 T:2316606352 M: 74117120 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(0.000000, 0)
18:16:18 T:2316606352 M: 74117120 INFO: CPCMRemap: Configured speaker layout: 5.1
18:16:18 T:3041535840 M: 74031104 DEBUG: Load VideoOverlay.xml: 156.73ms
18:16:18 T:3041535840 M: 74031104 DEBUG: Alloc resources: 157.10ms (157.10 ms skin load)
18:16:18 T:2316606352 M: 74031104 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,BL,BR
18:16:18 T:2316606352 M: 74031104 INFO: CPCMRemap: O channel map: FL,FR,CE,LFE,BL,BR
18:16:18 T:2316606352 M: 74031104 DEBUG: CPCMRemap: Downmix normalization is disabled
18:16:18 T:2316606352 M: 74031104 DEBUG: CPCMRemap: FL = FL(1.000000*)
18:16:18 T:2316606352 M: 74031104 DEBUG: CPCMRemap: FR = FR(1.000000*)
18:16:18 T:2316606352 M: 74031104 DEBUG: CPCMRemap: CE = CE(1.000000*)
18:16:18 T:2316606352 M: 74031104 DEBUG: CPCMRemap: LFE = LFE(1.000000*)
18:16:18 T:2316606352 M: 74031104 DEBUG: CPCMRemap: BL = BL(1.000000*)
18:16:18 T:2316606352 M: 74031104 DEBUG: CPCMRemap: BR = BR(1.000000*)
18:16:18 T:2316606352 M: 73846784 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, pass-through
18:16:18 T:3041535840 M: 73719808 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:16:18 T:2324999056 M: 73719808 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:16:18 T:2324999056 M: 73719808 NOTICE: running thread: video_thread
18:16:18 T:2324999056 M: 73719808 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:16:18 T:2324999056 M: 73719808 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 1)
18:16:18 T:2316606352 M: 73682944 INFO: AudioRendererFactory: not a explicit device, trying to autodetect.
18:16:18 T:2316606352 M: 73641984 DEBUG: RemoveActiveDevice - Removing device 1
18:16:18 T:2316606352 M: 73641984 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
18:16:18 T:2316606352 M: 73641984 DEBUG: RemoveActiveDevice - Removing device 0
18:16:18 T:2316606352 M: 73641984 WARNING: PulseAudio: Does not support passthrough
18:16:18 T:2316606352 M: 73641984 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
18:16:18 T:2316606352 M: 73641984 DEBUG: RemoveActiveDevice - Removing device 2
18:16:18 T:2316606352 M: 73641984 DEBUG: RemoveActiveDevice - Removing device 1
18:16:18 T:2316606352 M: 73641984 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
18:16:18 T:2316606352 M: 73641984 DEBUG: RemoveActiveDevice - Removing device 0
18:16:18 T:2316606352 M: 73641984 DEBUG: Initialize - using alsa device iec958:AES0=0x6,AES1=0x82,AES2=0x0,AES3=0x2
18:16:18 T:2316606352 M: 73261056 DEBUG: CALSADirectSound::Initialize - packet size:2048, packet count:16, buffer size:8192
18:16:18 T:2316606352 M: 73076736 DEBUG: CALSADirectSound::Initialize - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough true - audioDevice: iec958
18:16:18 T:2316606352 M: 73076736 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
18:16:18 T:2474638224 M: 72921088 DEBUG: CDVDPlayer::HandleMessages - player started 1
18:16:18 T:2324999056 M: 70746112 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:18 T:2474638224 M: 68902912 DEBUG: CDVDPlayer::SetCaching - caching state 0
18:16:18 T:2316606352 M: 68902912 WARNING: CALSADirectSound::GetSpace - get space failed. err: -32 (Broken pipe)
18:16:18 T:2316606352 M: 68870144 DEBUG: CDVDPlayerAudio:: Discontinuity - was:-98439.005000, should be:342066.906507, error:440505.911507
18:16:18 T:3041535840 M: 68767744 DEBUG: Mysql Start transaction
18:16:18 T:3041535840 M: 68702208 DEBUG: Mysql execute: DELETE FROM streamdetails WHERE idFile = 28
18:16:18 T:3041535840 M: 68640768 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strVideoCodec, fVideoAspect, iVideoWidth, iVideoHeight, iVideoDuration) VALUES (28,0,'wvc1',1.000000,1280,720,7947)
18:16:18 T:3041535840 M: 68554752 DEBUG: Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strAudioCodec, iAudioChannels, strAudioLanguage) VALUES (28,1,'wmapro',6,'')
18:16:18 T:3041535840 M: 68571136 DEBUG: Mysql commit transaction
18:16:18 T:3041535840 M: 68571136 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc
18:16:18 T:3041535840 M: 68632576 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://ANNIHILATE/Media/Movies/The 40 Year Old Virgin.wmv)
18:16:18 T:3041535840 M: 68542464 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:19 T:3041535840 M: 68575232 INFO: CheckIdle - Closing session to http://xoap.weather.com (easy=0xa3ad948, multi=0xa37bfe8)
18:16:19 T:3041535840 M: 68575232 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:22 T:2324999056 M: 68583424 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:22 T:3041535840 M: 68583424 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:31 T:2324999056 M: 68521984 DEBUG: Previous line repeats 1 times.
18:16:31 T:2324999056 M: 68521984 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:32 T:3041535840 M: 68530176 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:34 T:2324999056 M: 68530176 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:35 T:2324999056 M: 68530176 INFO: Previous line repeats 2 times.
18:16:35 T:2324999056 M: 68530176 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:37 T:3041535840 M: 68530176 WARNING: Previous line repeats 9 times.
18:16:37 T:3041535840 M: 68530176 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:38 T:2324999056 M: 68538368 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:38 T:2324999056 M: 68538368 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:39 T:2443701136 M: 68538368 WARNING: Previous line repeats 8 times.
18:16:39 T:2443701136 M: 68538368 DEBUG: Thread 2443701136 terminating (autodelete)
18:16:39 T:2435308432 M: 68538368 DEBUG: Thread 2435308432 terminating (autodelete)
18:16:39 T:2485123984 M: 68538368 DEBUG: Thread 2485123984 terminating (autodelete)
18:16:39 T:2452093840 M: 68554752 DEBUG: Thread 2452093840 terminating (autodelete)
18:16:39 T:2324999056 M: 68554752 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:41 T:2324999056 M: 68575232 WARNING: Previous line repeats 6 times.
18:16:41 T:2324999056 M: 68575232 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:41 T:2324999056 M: 68575232 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:42 T:2324999056 M: 68575232 WARNING: Previous line repeats 2 times.
18:16:42 T:2324999056 M: 68575232 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:42 T:2324999056 M: 68575232 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:42 T:2316606352 M: 68575232 DEBUG: CDVDPlayerAudio:: Discontinuity - was:23882766.545507, should be:23871418.885149, error:-11347.660358
18:16:42 T:3041535840 M: 68575232 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:42 T:2324999056 M: 68575232 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:42 T:2324999056 M: 68575232 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:43 T:2324999056 M: 68575232 WARNING: Previous line repeats 1 times.
18:16:43 T:2324999056 M: 68575232 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:43 T:2324999056 M: 68575232 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:44 T:2324999056 M: 68583424 WARNING: Previous line repeats 3 times.
18:16:44 T:2324999056 M: 68583424 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:44 T:2324999056 M: 68583424 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:44 T:2316606352 M: 68583424 DEBUG: CDVDPlayerAudio:: Discontinuity - was:25919929.855149, should be:25931821.670409, error:11891.815260
18:16:44 T:2324999056 M: 68583424 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:44 T:2324999056 M: 68583424 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:47 T:3041535840 M: 68521984 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:49 T:3041535840 M: 68530176 INFO: LIRC Initialize: using: /dev/lircd
18:16:49 T:3041535840 M: 68530176 DEBUG: Failed to connect to LIRC. Giving up.
18:16:49 T:2324999056 M: 68530176 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:49 T:3041535840 M: 68530176 DEBUG: SECTION:UnloadDll(libcurl-gnutls.so.4)
18:16:49 T:3041535840 M: 68530176 DEBUG: Unloading: libcurl-gnutls.so.4
18:16:52 T:3041535840 M: 68075520 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:54 T:2324999056 M: 66953216 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:54 T:2324999056 M: 66830336 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:55 T:2324999056 M: 66318336 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:16:55 T:2324999056 M: 66191360 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:16:57 T:3041535840 M: 66195456 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:16:59 T:2324999056 M: 66404352 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:17:02 T:3041535840 M: 66285568 INFO: Previous line repeats 2 times.
18:17:02 T:3041535840 M: 66285568 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:17:04 T:2324999056 M: 67764224 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:17:07 T:3041535840 M: 67825664 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:17:10 T:2324999056 M: 67047424 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:17:10 T:2324999056 M: 66969600 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:17:12 T:3041535840 M: 67022848 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:17:12 T:2324999056 M: 67022848 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:17:13 T:2515618704 M: 67022848 NOTICE: ES: Client from 127.0.0.1 timed out
18:17:17 T:3041535840 M: 66957312 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:17:19 T:2324999056 M: 66883584 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:17:21 T:2316606352 M: 66813952 INFO: Previous line repeats 1 times.
18:17:21 T:2316606352 M: 66813952 DEBUG: CDVDPlayerAudio:: Discontinuity - was:62795983.872409, should be:62785892.143523, error:-10091.728886
18:17:22 T:2316606352 M: 66813952 DEBUG: CDVDPlayerAudio:: Discontinuity - was:63810584.911523, should be:63828015.271992, error:17430.360470
18:17:22 T:3041535840 M: 66813952 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:17:23 T:2316606352 M: 66822144 DEBUG: CDVDPlayerAudio:: Discontinuity - was:64851759.745992, should be:64840291.385867, error:-11468.360126
18:17:25 T:3041535840 M: 66781184 NOTICE: CDVDPlayer::CloseFile()
18:17:25 T:2474638224 M: 66781184 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc
18:17:25 T:3041535840 M: 66818048 NOTICE: DVDPlayer: waiting for threads to exit
18:17:25 T:2474638224 M: 66813952 NOTICE: CDVDPlayer::OnExit()
18:17:25 T:2474638224 M: 66813952 NOTICE: DVDPlayer: closing audio stream
18:17:25 T:2474638224 M: 66813952 NOTICE: Closing audio stream
18:17:25 T:2474638224 M: 66813952 NOTICE: Waiting for audio thread to exit
18:17:25 T:2316606352 M: 66813952 NOTICE: thread end: CDVDPlayerAudio::OnExit()
18:17:25 T:2316606352 M: 66813952 DEBUG: Thread 2316606352 terminating
18:17:25 T:2474638224 M: 66850816 NOTICE: Closing audio device
18:17:25 T:2474638224 M: 66850816 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
18:17:25 T:2474638224 M: 66850816 DEBUG: RemoveActiveDevice - Removing device 2
18:17:25 T:2474638224 M: 67219456 NOTICE: Deleting audio codec
18:17:25 T:2474638224 M: 68231168 NOTICE: DVDPlayer: closing video stream
18:17:25 T:2474638224 M: 68272128 NOTICE: Closing video stream
18:17:25 T:2474638224 M: 68272128 NOTICE: waiting for video thread to exit
18:17:25 T:2324999056 M: 68272128 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
18:17:25 T:2324999056 M: 68272128 NOTICE: thread end: video_thread
18:17:25 T:2324999056 M: 68272128 DEBUG: Thread 2324999056 terminating
18:17:25 T:2474638224 M: 68280320 NOTICE: deleting video codec
18:17:25 T:2333391760 M: 68321280 DEBUG: CrystalHD: Output Thread Stopped...
18:17:25 T:2333391760 M: 68321280 DEBUG: Thread 2333391760 terminating
18:17:25 T:2474638224 M:141119488 DEBUG: CrystalHD: device closed
18:17:25 T:2474638224 M:141180928 NOTICE: CDVDPlayer::OnExit() deleting demuxer
18:17:25 T:2474638224 M:141176832 NOTICE: CDVDPlayer::OnExit() deleting input stream
18:17:25 T:2474638224 M:141176832 DEBUG: CFileSMB::Close closing fd 10000
18:17:25 T:2474638224 M:141193216 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc
18:17:25 T:2474638224 M:141185024 DEBUG: Thread 2474638224 terminating
18:17:25 T:3041535840 M:141307904 NOTICE: DVDPlayer: finished waiting
18:17:25 T:3041535840 M:141307904 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:17:25 T:3041535840 M:141307904 NOTICE: CDVDPlayer::CloseFile()
18:17:25 T:3041535840 M:141307904 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:17:25 T:3041535840 M:141307904 NOTICE: DVDPlayer: waiting for threads to exit
18:17:25 T:3041535840 M:141307904 NOTICE: DVDPlayer: finished waiting
18:17:25 T:3041535840 M:141307904 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:17:25 T:2474638224 M:142487552 DEBUG: thread start, auto delete: 1
18:17:25 T:2474638224 M:142528512 DEBUG: DoWork - Saving file state for video item smb://ANNIHILATE/Media/Movies/The 40 Year Old Virgin.wmv
18:17:25 T:3041535840 M:142487552 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-i486-linux.so)
18:17:25 T:3041535840 M:142528512 DEBUG: Unloading: ImageLib-i486-linux.so
18:17:26 T:3041535840 M:142544896 DEBUG: ------ Window Deinit (VideoOverlay.xml) ------
18:17:26 T:2474638224 M:142553088 DEBUG: Mysql execute: update files set playCount=NULL,lastPlayed='2010-10-14 18:17:26' where idFile=28
18:17:26 T:2474638224 M:142553088 DEBUG: Mysql execute: insert into bookmark (idBookmark, idFile, timeInSeconds, totalTimeInSeconds, thumbNailImage, player, playerState, type) values(NULL,28,66.648003,7947.000000,'','DVDPlayer','', 1)
18:17:26 T:2474638224 M:142557184 DEBUG: Mysql execute: INSERT INTO settings (idFile,Deinterlace,ViewMode,ZoomAmount,PixelRatio,AudioStream,SubtitleStream,SubtitleDelay,SubtitlesOn,Brightness,Contrast,Gamma,VolumeAmplification,AudioDelay,OutputToAllSpeakers,ResumeTime,Crop,CropLeft,CropRight,CropTop,CropBottom,Sharpness,NoiseReduction,NonLinStretch) VALUES (28,0,0,1.000000,1.000000,-1,-1,0.000000,0,50.000000,50.000000,20.000000,0.000000,0.000000,0,0,0,0,0,0,0,0.000000,0.000000,0)
18:17:27 T:3041535840 M:142540800 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:17:55 T:3041535840 M:142561280 DEBUG: Previous line repeats 5 times.
18:17:55 T:3041535840 M:142561280 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avformat-52-i486-linux.so)
18:17:55 T:3041535840 M:142561280 DEBUG: Unloading: avformat-52-i486-linux.so
18:17:56 T:2474638224 M:142692352 DEBUG: Thread 2474638224 terminating (autodelete)
18:17:57 T:3041535840 M:142704640 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying
18:18:26 T:2515618704 M:146489344 DEBUG: Previous line repeats 5 times.
18:18:26 T:2515618704 M:146489344 NOTICE: ES: Client from 127.0.0.1 timed out
18:18:27 T:3041535840 M:146550784 DEBUG: HttpApi Start command: getcurrentlyplaying paras: getcurrentlyplaying