Is it possible to use buffering without the annoying "Cache Full" pop up?
#16
Please remove your log and place it on pastebin.

I agree it is annoying.
If I have helped you or increased your knowledge, click the 'thumbs up' button to give thanks :) (People with less than 20 posts won't see the "thumbs up" button.)
Reply
#17
(2015-02-01, 08:54)Blazemonkey Wrote: The pop-up isn't an error message, it's telling you that you have filled the cache.

The cache is almost always full, and the message is not there to tell you that. It is there to tell you that there isn't enough video in the cache to play the video without stopping to buffer again (at least, that is what Kodi believes will happen according to whatever formula it used for the current rate of video data coming in).

Quote:The pop-up will appear if you have caching enabled and set to an amount that smaller than the video file, on hardware fast enough to fill that cache before the cache reaches the end of the video file.
Again, that is completely incorrect. The cache is always filling and emptying as it plays back.

Quote:So basically, if you want to use the cache, and not be annoyed by this pop-up, you'll have to set the cache to something greater than the size of the files you are playing.

Again, no. Consider that Kodi runs on the Raspberry Pi A and A+, which only has 256 MB of RAM, and that the cache size is three times the video size, so that's maybe 50mb that the Pi-A can cache. And yet I could show you an A that runs all day long and never showing a cache full message, even when using 20GB BR ISO files.

Once more, this is not an indicator that the cache has filled. This is a message that is only supposed to come up when Kodi detects that it will have to stop and buffer, and Kodi is probably not detecting this very accurately, so the message comes up when it should not. It's also entirely possible that this is a bug that has existed since v12. I've seen this false-positive cache message happen only sometimes, and only when seeking, and I'm sure even that situation could be improved upon. From what you describe, it's happening much more than even that, and I understand your frustration.

It is a bug. No one was so pants-on-head retarded that they thought it was a good idea to add a notification to tell you some completely useless technical details about the cache while you are trying to enjoy a movie. It. Is. A. Bug.
Reply
#18
Whats next?
Will you open a ticket?
Reply
#19
(2015-02-01, 10:26)Ned Scott Wrote: I've seen this false-positive cache message happen only sometimes, and only when seeking, and I'm sure even that situation could be improved upon.
This is my experience on an RK3188 Android Arm box. It only happens sometimes and right after I seek. I just ignore it and have never had playback issues so sounds more like an algorithm tweak is in order. But then again *I* am pants-on-head retarded sometimes so what do I know. Wink
Reply
#20
Hi,

I'm getting this error all the time. I have my films on a NAS connected to the network by gigabit. Watching on my fire TV box.

Was this bug fixed with the Isengard release?
Thanks
Reply
#21
A lot of false positives with the message have been fixed for v15. Calculations for both the message and for stopping to buffer are a lot more accurate now.
Reply
#22
Quote:
(2015-02-01, 05:40)doug Wrote: Ned was just being funny. He could have posted something like "I'm not sure why you bothered to post such a useless request for support. Since it doesn't have a bit of information that would allow us to help you." Big Grin In essence, you've provided only marginally more detail than "I have a problem."

As per the forum pinned thread, a kodi.log, your setup, add-ons where it's happening, filetypes for which it happening, etc. would be helpful.

The reason I didn't post more information than I did is because I figured that anybody who actually knows how to enable the cache would've seen this pop-up by now.

The pop-up isn't an error message, it's telling you that you have filled the cache. It's not a configuration issue.. With video cache enabled, the pop-up is repeatable on every version of XBMC/Kodi since at least 12, on any hardware, running any OS, using any build of Kodi, with probably every type of video file. It appears on my OpenELEC box, my AppleTV 2, my Windows 7 desktop with an i7 4770 & 2x GTX770'd in SLI, my Windows 8.1 G750JH laptop, and my Surface Pro tablet, and always has since I started using XBMC.

The pop-up will appear if you have caching enabled and set to an amount that smaller than the video file, on hardware fast enough to fill that cache before the cache reaches the end of the video file. Why do I need caching if my hardware is fast enough? Simple, Wifi. It generally works very well but there are times when a little bit of cache is needed. But, that's besides the point of this thread.

To satisfy those who think this is an issue related to software configuration, hardware configuration, or file types, I've created a brand new Windows 7 virtual machine, and setup a 1GB RAM disk to store a couple of low bitrate files of various formats, mkv, xvid, mpeg4, etc, then I installed the newest version of Kodi, enabled the cache and the log, and then played the video files, same thing on a brand new configuration.

Here's something interesting I just noticed, I'm not sure why, but I was able to see the full text of the pop-up when running it in the VM. It says: "Cache Full: Cache filled before reaching required amount for continuous playback" Here's a screenshot. So this is basically saying that the cache has been filled before it was able to cache to the end of the file.

So basically, if you want to use the cache, and not be annoyed by this pop-up, you'll have to set the cache to something greater than the size of the files you are playing.

Code:
22:59:41 T:3632  NOTICE: special://profile/ is mapped to: special://masterprofile/
22:59:41 T:3632  NOTICE: -----------------------------------------------------------------------
22:59:41 T:3632  NOTICE: Starting Kodi (14.1 Git:20150129-38e4046). Platform: Windows NT x86 32-bit
22:59:41 T:3632  NOTICE: Using Release Kodi x32 build
22:59:41 T:3632  NOTICE: Kodi compiled Jan 29 2015 by MSVC 180030723 for Windows NT x86 32-bit version 6.0 (0x06000000)
22:59:41 T:3632  NOTICE: Running on Windows 7 SP1, kernel: Windows NT x86 64-bit version 6.1
22:59:41 T:3632  NOTICE: Host CPU: Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz, 2 cores available
22:59:41 T:3632  NOTICE: Desktop Resolution: 1024x768 32Bit at 60Hz
22:59:41 T:3632  NOTICE: Running with administrator rights
22:59:41 T:3632  NOTICE: Aero is enabled
22:59:41 T:3632  NOTICE: special://xbmc/ is mapped to: C:\Program Files (x86)\Kodi
22:59:41 T:3632  NOTICE: special://xbmcbin/ is mapped to: C:\Program Files (x86)\Kodi
22:59:41 T:3632  NOTICE: special://masterprofile/ is mapped to: C:\Users\Blazemonkey\AppData\Roaming\Kodi\userdata
22:59:41 T:3632  NOTICE: special://home/ is mapped to: C:\Users\Blazemonkey\AppData\Roaming\Kodi\
22:59:41 T:3632  NOTICE: special://temp/ is mapped to: C:\Users\Blazemonkey\AppData\Roaming\Kodi\cache
22:59:41 T:3632  NOTICE: The executable running is: C:\Program Files (x86)\Kodi\Kodi.exe
22:59:41 T:3632  NOTICE: Local hostname: Rob-VM-Win7
22:59:41 T:3632  NOTICE: Log File is located: C:\Users\Blazemonkey\AppData\Roaming\Kodi\kodi.log
22:59:41 T:3632  NOTICE: -----------------------------------------------------------------------
22:59:41 T:3632  NOTICE: load settings...
22:59:41 T:3632 WARNING: CSettingString: unknown options filler "timezonecountries" of "locale.timezonecountry"
22:59:41 T:3632 WARNING: CSettingString: unknown options filler "timezones" of "locale.timezone"
22:59:41 T:3632  NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
22:59:41 T:3632  NOTICE: Loaded settings file from special://profile/advancedsettings.xml
22:59:41 T:3632  NOTICE: Contents of special://profile/advancedsettings.xml are...
                                            <advancedsettings>
                                              <network>
                                                <buffermode>1</buffermode>
                                                <cachemembuffersize>1048576</cachemembuffersize>
                                                <readbufferfactor>5</readbufferfactor>
                                              </network>
                                              <loglevel>1</loglevel>
                                            </advancedsettings>
22:59:41 T:3632  NOTICE: Log level changed to "LOG_LEVEL_DEBUG"
22:59:41 T:3632  NOTICE: Default DVD Player: dvdplayer
22:59:41 T:3632  NOTICE: Default Video Player: dvdplayer
22:59:41 T:3632  NOTICE: Default Audio Player: paplayer
22:59:41 T:3632  NOTICE: Disabled debug logging due to GUI setting. Level 1.
22:59:41 T:3632  NOTICE: Log level changed to "LOG_LEVEL_DEBUG"
22:59:41 T:3632  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
22:59:41 T:3632  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
22:59:41 T:3632   DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
22:59:41 T:3632   DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
22:59:41 T:3632   DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
22:59:41 T:3632   DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
22:59:41 T:3632  NOTICE: Loaded playercorefactory configuration
22:59:41 T:3632  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
22:59:41 T:3632  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
22:59:41 T:3632    INFO: creating subdirectories
22:59:41 T:3632    INFO: userdata folder: special://masterprofile/
22:59:41 T:3632    INFO: recording folder:
22:59:41 T:3632    INFO: screenshots folder:
22:59:41 T:3632    INFO: load language info file: special://xbmc/language/English/langinfo.xml
22:59:41 T:3632   DEBUG: trying to set locale to eng_usa
22:59:41 T:3632    INFO: global locale set to English_United States.1252
22:59:41 T:3632    INFO: load English language file, from path: special://xbmc/language/
22:59:41 T:3632   DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files (x86)\Kodi\language\English\strings.po
22:59:41 T:3632   DEBUG: POParser: loaded 3267 strings from file C:\Program Files (x86)\Kodi\language\English\strings.po
22:59:41 T:2172  NOTICE: Thread ActiveAE start, auto delete: false
22:59:41 T:2224  NOTICE: Thread AESink start, auto delete: false
22:59:41 T:2172  NOTICE: Found 2 Lists of Devices
22:59:41 T:2172  NOTICE: Enumerated DIRECTSOUND devices:
22:59:41 T:2172  NOTICE:     Device 1
22:59:41 T:2172  NOTICE:         m_deviceName      : {E1B705FF-66B0-4E34-80DE-E9E264764A32}
22:59:41 T:2172  NOTICE:         m_displayName     : Speakers - Speakers (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_displayNameExtra: DIRECTSOUND: Speakers (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:59:41 T:2172  NOTICE:         m_channels        : FL,FR
22:59:41 T:2172  NOTICE:         m_sampleRates     : 44100
22:59:41 T:2172  NOTICE:         m_dataFormats     : AE_FMT_FLOAT
22:59:41 T:2172  NOTICE:     Device 2
22:59:41 T:2172  NOTICE:         m_deviceName      : {FEC35C21-2484-438A-8B96-721B1F18F1C2}
22:59:41 T:2172  NOTICE:         m_displayName     : Headphones - Headphones (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_displayNameExtra: DIRECTSOUND: Headphones (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:59:41 T:2172  NOTICE:         m_channels        : FL,FR
22:59:41 T:2172  NOTICE:         m_sampleRates     : 44100
22:59:41 T:2172  NOTICE:         m_dataFormats     : AE_FMT_FLOAT
22:59:41 T:2172  NOTICE:     Device 3
22:59:41 T:2172  NOTICE:         m_deviceName      : default
22:59:41 T:2172  NOTICE:         m_displayName     : default
22:59:41 T:2172  NOTICE:         m_displayNameExtra:
22:59:41 T:2172  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:59:41 T:2172  NOTICE:         m_channels        : FL,FR
22:59:41 T:2172  NOTICE:         m_sampleRates     : 44100
22:59:41 T:2172  NOTICE:         m_dataFormats     : AE_FMT_FLOAT
22:59:41 T:2172  NOTICE: Enumerated WASAPI devices:
22:59:41 T:2172  NOTICE:     Device 1
22:59:41 T:2172  NOTICE:         m_deviceName      : {E1B705FF-66B0-4E34-80DE-E9E264764A32}
22:59:41 T:2172  NOTICE:         m_displayName     : Speakers - Speakers (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_displayNameExtra: WASAPI: Speakers (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:59:41 T:2172  NOTICE:         m_channels        : FL,FR
22:59:41 T:2172  NOTICE:         m_sampleRates     : 44100
22:59:41 T:2172  NOTICE:         m_dataFormats     :
22:59:41 T:2172  NOTICE:     Device 2
22:59:41 T:2172  NOTICE:         m_deviceName      : {FEC35C21-2484-438A-8B96-721B1F18F1C2}
22:59:41 T:2172  NOTICE:         m_displayName     : Headphones - Headphones (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_displayNameExtra: WASAPI: Headphones (High Definition Audio Device)
22:59:41 T:2172  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:59:41 T:2172  NOTICE:         m_channels        : FL,FR
22:59:41 T:2172  NOTICE:         m_sampleRates     : 44100
22:59:41 T:2172  NOTICE:         m_dataFormats     :
22:59:41 T:2172  NOTICE:     Device 3
22:59:41 T:2172  NOTICE:         m_deviceName      : default
22:59:41 T:2172  NOTICE:         m_displayName     : default
22:59:41 T:2172  NOTICE:         m_displayNameExtra:
22:59:41 T:2172  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:59:41 T:2172  NOTICE:         m_channels        : FL,FR
22:59:41 T:2172  NOTICE:         m_sampleRates     : 44100
22:59:41 T:2172  NOTICE:         m_dataFormats     :
22:59:41 T:2224    INFO: CActiveAESink::OpenSink - initialize sink
22:59:41 T:2224   DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
22:59:41 T:2224   DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 65552
22:59:41 T:2224   DEBUG: CAESinkDirectSound::Initialize: secondary buffer created
22:59:41 T:2224   DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
22:59:41 T:2224   DEBUG:   Audio Device    : Headphones (High Definition Audio Device)
22:59:41 T:2224   DEBUG:   Sample Rate     : 44100
22:59:41 T:2224   DEBUG:   Sample Format   : AE_FMT_FLOAT
22:59:41 T:2224   DEBUG:   Bits Per Sample : 32
22:59:41 T:2224   DEBUG:   Valid Bits/Samp : 32
22:59:41 T:2224   DEBUG:   Channel Count   : 2
22:59:41 T:2224   DEBUG:   Block Align     : 8
22:59:41 T:2224   DEBUG:   Avg. Bytes Sec  : 352800
22:59:41 T:2224   DEBUG:   Samples/Block   : 32
22:59:41 T:2224   DEBUG:   Format cBSize   : 22
22:59:41 T:2224   DEBUG:   Channel Layout  : FL,FR
22:59:41 T:2224   DEBUG:   Channel Mask    : 3
22:59:41 T:2224   DEBUG:   Frames          : 661
22:59:41 T:2224   DEBUG:   Frame Samples   : 1322
22:59:41 T:2224   DEBUG:   Frame Size      : 8
22:59:41 T:2224   DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
22:59:41 T:2224   DEBUG:   Output Device : default
22:59:41 T:2224   DEBUG:   Sample Rate   : 44100
22:59:41 T:2224   DEBUG:   Sample Format : AE_FMT_FLOAT
22:59:41 T:2224   DEBUG:   Channel Count : 2
22:59:41 T:2224   DEBUG:   Channel Layout: FL,FR
22:59:41 T:2224   DEBUG:   Frames        : 661
22:59:41 T:2224   DEBUG:   Frame Samples : 1322
22:59:41 T:2224   DEBUG:   Frame Size    : 8
22:59:41 T:3632  NOTICE: Running database version Addons16
22:59:41 T:3632   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/cpluff.dll)
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
22:59:41 T:2224   DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-win32.xbmc.org has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
22:59:41 T:3632   DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
22:59:41 T:3632  NOTICE: ADDONS: Using repository repository.xbmc.org
22:59:41 T:3632  NOTICE: ADDONS: Using repository repository.pvr-win32.xbmc.org
22:59:41 T:612  NOTICE: Thread RemoteControl start, auto delete: false
22:59:41 T:3632   DEBUG: PERIPHERALS::CPeripherals::LoadMappings - loaded node "Motorola Nyxboard Hybrid"
22:59:41 T:3632   DEBUG: PERIPHERALS::CPeripherals::LoadMappings - loaded node "CEC Adapter"
22:59:41 T:3632   DEBUG: PERIPHERALS::CPeripherals::LoadMappings - loaded node "Pulse-Eight CEC Adapter"
22:59:41 T:612   DEBUG: CRemoteControl::Connect - connecting to: ::1:24000 ...
22:59:41 T:3632   DEBUG: PERIPHERALS::CPeripherals::LoadMappings - loaded node "iMON HID device"
22:59:41 T:3632   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcec.dll)
22:59:41 T:3864  NOTICE: Thread PeripBusCEC start, auto delete: false
22:59:41 T:976  NOTICE: Thread PeripBusUSB start, auto delete: false
22:59:41 T:3632   DEBUG: CWIN32Util::GetDriveStatus: Requesting status for drive \\.\D:.
22:59:41 T:3632   DEBUG: CWIN32Util::GetDriveStatus: Requesting media status for drive \\.\D:.
22:59:41 T:3632   DEBUG: CWIN32Util::GetDriveStatus: Requesting status for drive \\.\E:.
22:59:41 T:3632   DEBUG: CWIN32Util::GetDriveStatus: Requesting media status for drive \\.\E:.
22:59:41 T:3632  NOTICE: Found screen: Generic Non-PnP Monitor on VirtualBox Graphics Adapter for Windows Vista and 7, adapter 0.
22:59:41 T:3632  NOTICE: Primary mode: 1024x768@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 800x600@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 640x480@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 1024x768@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 1152x864@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 1280x960@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 1280x1024@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 1400x1050@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Additional mode: 1600x1200@ 60.00 - Full Screen
22:59:41 T:3632  NOTICE: Checking resolution 16
22:59:41 T:3632   DEBUG: CWinEventsWin32::WndProc: window resize event
22:59:41 T:3632   DEBUG: CWinEventsWin32::WndProc: window move event
22:59:41 T:3632   DEBUG: CWinSystemWin32::ResizeInternal - resizing due to size change (0,0,1024,768 fullscreen)->(0,0,1024,768 fullscreen)
22:59:41 T:3632   DEBUG: CWinEventsWin32::WndProcWindow is active
22:59:41 T:3632   DEBUG: CRenderSystemDX::InitRenderSystem - trying D3D9Ex...
22:59:41 T:3632   DEBUG: CRenderSystemDX::InitRenderSystem - using D3D9Ex
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice on adapter 0
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice - using hardware vertex processing
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice - adapter 0: VBoxDispD3D-x86.dll, VirtualBox Graphics Adapter for Windows Vista and 7, VendorId 33006, DeviceId 48879
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice - using D3DCAPS2_DYNAMICTEXTURES
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice - texture caps: 0x0007ECC5
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice - RENDER_CAPS_DXT
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice - RENDER_CAPS_NPOT
22:59:41 T:3632   DEBUG: CRenderSystemDX::CreateDevice - RENDER_CAPS_DXT_NPOT
22:59:41 T:3632   DEBUG: CWinSystemWin32::SetFullScreen (windowed fullscreen) on screen 0 with size 1024x768, refresh 60.000000
22:59:41 T:3632   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
22:59:41 T:3632    INFO: load default splash image: C:\Program Files (x86)\Kodi\media\Splash.png
22:59:41 T:3632   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib.dll)
22:59:41 T:3632    INFO: load keymapping
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/appcommand.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/gamepad.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Nintendo.Wii.U.Pro.Controller.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/keyboard.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/mouse.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/remote.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
22:59:41 T:3632    INFO: Loading special://xbmc/system/IRSSmap.xml
22:59:41 T:3632    INFO: * Adding remote mapping for device 'Microsoft MCE'
22:59:41 T:3632    INFO: * Adding remote mapping for device 'Abstract'
22:59:41 T:3632   DEBUG: CButtonTranslator::Load - no userdata IRSSmap.xml found, skipping
22:59:41 T:3632    INFO: GUI format 1024x768, Display 1024x768@ 60.00 - Full Screen
22:59:41 T:3632   DEBUG: guilib: Fill viewport on change for solving rendering passes
22:59:41 T:3632   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcurl.dll)
22:59:41 T:3632  NOTICE: Running database version Addons16
22:59:41 T:3632   DEBUG: CDatabaseManager::Initialize, updating databases...
22:59:41 T:3632  NOTICE: Running database version ViewModes6
22:59:41 T:3632  NOTICE: Running database version Textures13
22:59:41 T:3632  NOTICE: Running database version MyMusic48
22:59:41 T:3632  NOTICE: Running database version MyVideos90
22:59:41 T:3632  NOTICE: Running database version TV26
22:59:41 T:3632  NOTICE: Running database version Epg8
22:59:41 T:3632   DEBUG: CDatabaseManager::Initialize, updating databases... DONE
22:59:41 T:3632   DEBUG: DPMS: supported power-saving modes: OFF STANDBY
22:59:41 T:3632   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
22:59:41 T:3632   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
22:59:41 T:3632    INFO: Unloading old skin ...
22:59:41 T:3632    INFO:   load skin from: C:\Program Files (x86)\Kodi\addons\skin.confluence (version: 2.5.12)
22:59:41 T:3632    INFO:   load fonts for skin...
22:59:41 T:3632    INFO: Loading fonts from C:\Program Files (x86)\Kodi\addons\skin.confluence\720p\Font.xml
22:59:41 T:3632   DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files (x86)\Kodi\addons\skin.confluence\language\English\strings.po
22:59:41 T:3632   DEBUG: POParser: loaded 149 strings from file C:\Program Files (x86)\Kodi\addons\skin.confluence\language\English\strings.po
22:59:42 T:3632    INFO: Loading skin includes from C:\Program Files (x86)\Kodi\addons\skin.confluence\720p\includes.xml
22:59:42 T:3632    INFO:   load new skin...
22:59:42 T:3632    INFO: Loading user windows, path C:\Program Files (x86)\Kodi\addons\skin.confluence\720p
22:59:42 T:3632   DEBUG: Load Skin XML: 13.48ms
22:59:42 T:3632    INFO:   initialize new skin...
22:59:42 T:3632   DEBUG: guilib: Fill viewport on change for solving rendering passes
22:59:42 T:3632    INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
22:59:42 T:3632   DEBUG: CTextureBundleXBT::OpenBundle - Opened bundle C:\Program Files (x86)\Kodi\addons\skin.confluence\media\Textures.xbt
22:59:42 T:3632    INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
22:59:42 T:3632    INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
22:59:42 T:3632    INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
22:59:42 T:3632    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
22:59:42 T:3632    INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
22:59:42 T:3632    INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
22:59:42 T:3632    INFO: Loading C:\Program Files (x86)\Kodi\addons\skin.confluence\sounds\sounds.xml
22:59:42 T:3632    INFO:   skin loaded...
22:59:42 T:3632   DEBUG: JSONRPC: JSON schema type broadcastnext references an unknown type PVR.Details.Broadcast
22:59:42 T:3632 WARNING: JSONRPC: Could not parse type "PVR.Details.Channel"
22:59:42 T:3632    INFO: JSONRPC: Adding type "PVR.Details.Channel" to list of incomplete definitions (waiting for "PVR.Details.Broadcast")
22:59:42 T:3632   DEBUG: JSONRPC: JSON schema type  references an unknown type PVR.Details.Channel
22:59:42 T:3632   DEBUG: Invalid item definition in "items" for type channels
22:59:42 T:3632 WARNING: JSONRPC: Could not parse type "PVR.Details.ChannelGroup.Extended"
22:59:42 T:3632    INFO: JSONRPC: Adding type "PVR.Details.ChannelGroup.Extended" to list of incomplete definitions (waiting for "PVR.Details.Channel")
22:59:42 T:3632    INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Broadcast
22:59:42 T:3632    INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Channel
22:59:42 T:3632   DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
22:59:42 T:3632 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
22:59:42 T:3632    INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
22:59:42 T:3632    INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
22:59:42 T:3632   DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
22:59:42 T:3632 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
22:59:42 T:3632    INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
22:59:42 T:3632    INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
22:59:42 T:3632    INFO: JSONRPC v6.21.2: Successfully initialized
22:59:42 T:3632   DEBUG: ADDON: Starting service addons.
22:59:42 T:3632   DEBUG: Activating window ID: 12999
22:59:42 T:3632   DEBUG: ------ Window Init (Startup.xml) ------
22:59:42 T:3632    INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
22:59:42 T:3208  NOTICE: Thread LanguageInvoker start, auto delete: false
22:59:42 T:3632    INFO: removing tempfiles
22:59:42 T:3208    INFO: initializing python engine.
22:59:42 T:3632   DEBUG: ADDON: Starting service addons.
22:59:42 T:3632  NOTICE: initialize done
22:59:42 T:3632   DEBUG: CJoystick::Initialize : No Joystick found
22:59:42 T:3632  NOTICE: Running the application...
22:59:42 T:3632   DEBUG: Activating window ID: 10000
22:59:42 T:3208   DEBUG: CPythonInvoker(0, C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck\service.py): start processing
22:59:42 T:3632   DEBUG: ------ Window Init (Pointer.xml) ------
22:59:42 T:3208  NOTICE: -->Python Interpreter Initialized<--
22:59:42 T:3208   DEBUG: CPythonInvoker(0, C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck\service.py): the source file to load is "C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck\service.py"
22:59:42 T:3208   DEBUG: CPythonInvoker(0, C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck\service.py): setting the Python path to C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck;C:\Program Files (x86)\Kodi\system\python\DLLs;C:\Program Files (x86)\Kodi\system\python\Lib;C:\Program Files (x86)\Kodi\python27.zip;C:\Program Files (x86)\Kodi\system\python\lib\plat-win;C:\Program Files (x86)\Kodi\system\python\lib\lib-tk;C:\Program Files (x86)\Kodi;C:\Program Files (x86)\Kodi\system\python;C:\Program Files (x86)\Kodi\system\python\lib\site-packages
22:59:42 T:3208   DEBUG: CPythonInvoker(0, C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck\service.py): entering source directory C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck
22:59:42 T:3208   DEBUG: CPythonInvoker(0, C:\Program Files (x86)\Kodi\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
22:59:42 T:3632   DEBUG: ------ Window Deinit (Startup.xml) ------
22:59:42 T:3208   DEBUG: Version Check: Version 0.3.2 started
22:59:42 T:3632   DEBUG: ------ Window Init (Home.xml) ------
22:59:42 T:3632    INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
22:59:42 T:2136  NOTICE: Thread JobWorker start, auto delete: true
22:59:42 T:3632   DEBUG: no profile autoexec.py (C:\Users\Blazemonkey\AppData\Roaming\Kodi\userdata\autoexec.py) found, skipping
22:59:42 T:3632   DEBUG: CNetwork::NetworkMessage - Starting network services
22:59:42 T:2136   DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
22:59:42 T:3632  NOTICE: ES: Starting event server
22:59:42 T:3632    INFO: JSONRPC Server: Successfully initialized
22:59:42 T:932  NOTICE: Thread EventServer start, auto delete: false
22:59:42 T:932  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
22:59:42 T:932  NOTICE: UDP: Listening on port 9777
22:59:42 T:2136   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
22:59:42 T:3296  NOTICE: Thread RSSReader start, auto delete: false
22:59:42 T:2188  NOTICE: Thread TCPServer start, auto delete: false
22:59:42 T:2136   DEBUG: CMusicDatabase::GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
22:59:42 T:3296   DEBUG: CurlFile::Open(0CDCF1D8) http://feeds.xbmc.org/xbmc
22:59:42 T:3296    INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://feeds.xbmc.org
22:59:42 T:2136   DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
22:59:42 T:2136   DEBUG: CVideoDatabase::RunQuery took 0 ms for 0 items query: select * from movieview  ORDER BY dateAdded desc, idMovie desc LIMIT 10
22:59:42 T:2136   DEBUG: CVideoDatabase::RunQuery took 1 ms for 0 items query: select * from episodeview  ORDER BY dateAdded desc, idEpisode desc LIMIT 10
22:59:42 T:2136   DEBUG: CVideoDatabase::RunQuery took 0 ms for 0 items query: select * from musicvideoview  ORDER BY dateAdded desc, idMVideo desc LIMIT 10
22:59:42 T:2136   DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
22:59:42 T:612   DEBUG: CRemoteControl::Connect - connecting to: 127.0.0.1:24000 ...
22:59:42 T:3296   DEBUG: Got rss feed: http://feeds.xbmc.org/xbmc
22:59:42 T:3296   DEBUG: RSS feed encoding: UTF-8
22:59:42 T:3296   DEBUG: Parsed rss feed: http://feeds.xbmc.org/xbmc
22:59:42 T:3296   DEBUG: CurlFile::Open(0CDCF1D8) http://feeds.xbmc.org/latest_xbmc_addons
22:59:42 T:3296   DEBUG: Got rss feed: http://feeds.xbmc.org/latest_xbmc_addons
22:59:42 T:3296   DEBUG: RSS feed encoding: UTF-8
22:59:42 T:3296   DEBUG: Parsed rss feed: http://feeds.xbmc.org/latest_xbmc_addons
22:59:42 T:3296   DEBUG: CurlFile::Open(0CDCF1D8) http://feeds.xbmc.org/updated_xbmc_addons
22:59:42 T:3296   DEBUG: Got rss feed: http://feeds.xbmc.org/updated_xbmc_addons
22:59:42 T:3296   DEBUG: RSS feed encoding: UTF-8
22:59:42 T:3296   DEBUG: Parsed rss feed: http://feeds.xbmc.org/updated_xbmc_addons
22:59:42 T:3296   DEBUG: Thread RSSReader 3296 terminating
22:59:43 T:612   DEBUG: CRemoteControl::Connect - failed to connect
22:59:43 T:612    INFO: CRemoteControl::Process - failed to connect to irss, will keep retrying every 5 seconds
22:59:45 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
22:59:45 T:3632   DEBUG: Activating window ID: 10025
22:59:45 T:3632   DEBUG: ------ Window Deinit (Home.xml) ------
22:59:45 T:3632   DEBUG: ------ Window Init (MyVideoNav.xml) ------
22:59:45 T:3632    INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
22:59:45 T:3632   DEBUG: CGUIMediaWindow::GetDirectory ()
22:59:45 T:3632   DEBUG:   ParentPath = []
22:59:45 T:4032  NOTICE: Thread BackgroundLoader start, auto delete: false
22:59:45 T:4032   DEBUG: Thread BackgroundLoader 4032 terminating
22:59:47 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
22:59:47 T:3632   DEBUG: CGUIMediaWindow::GetDirectory (library://video/files.xml/)
22:59:47 T:3632   DEBUG:   ParentPath = []
22:59:47 T:1208  NOTICE: Thread BackgroundLoader start, auto delete: false
22:59:47 T:1208   DEBUG: Thread BackgroundLoader 1208 terminating
22:59:47 T:3208    INFO: CPythonInvoker(0, C:\Program Files (x86)\Kodi\addons\service.xbmc.versioncheck\service.py): script successfully run
22:59:47 T:3208    INFO: Python script stopped
22:59:47 T:3208   DEBUG: Thread LanguageInvoker 3208 terminating
22:59:47 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
22:59:47 T:3632   DEBUG: CGUIMediaWindow::GetDirectory (F:\)
22:59:47 T:3632   DEBUG:   ParentPath = [sources://video/]
22:59:47 T:3632   DEBUG: CVideoDatabase::RunQuery took 0 ms for 1 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=3
22:59:47 T:3156  NOTICE: Thread BackgroundLoader start, auto delete: false
22:59:48 T:3156   DEBUG: Thread BackgroundLoader 3156 terminating
22:59:48 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
22:59:48 T:3632   DEBUG: CGUIWindowVideoBase::OnPlayMedia F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi
22:59:48 T:3632   DEBUG: CVideoDatabase::GetMovieId (F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi), query = select idMovie from movie where idFile=7
22:59:48 T:3632   DEBUG: CVideoDatabase::GetEpisodeId (F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi), query = select idEpisode from episode where idFile=7
22:59:48 T:3632   DEBUG: CVideoDatabase::GetMusicVideoId (F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi), query = select idMVideo from musicvideo where idFile=7
22:59:48 T:3632   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
22:59:48 T:3632   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
22:59:48 T:3632   DEBUG: Loading settings for F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi
22:59:48 T:3632   DEBUG: CPlayerCoreFactory::GetPlayers(F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi)
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
22:59:48 T:3632   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
22:59:48 T:3632   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
22:59:48 T:3632   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
22:59:48 T:3632   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
22:59:48 T:3632   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
22:59:48 T:3632   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
22:59:48 T:3632   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
22:59:48 T:3632  NOTICE: DVDPlayer: Opening: F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi
22:59:48 T:3632 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:59:48 T:3632   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
22:59:48 T:3632  NOTICE: DXVA::CProcessorHD::PreInit - The Direct3d device doesn't support DXVA-HD.
22:59:48 T:3632  NOTICE: CWinRenderer::Preinit - could not init DXVA-HD processor - skipping
22:59:48 T:3632   DEBUG: DXVA - checking samples array size using 842094158 render target
22:59:48 T:3632   DEBUG: DXVA - render target not supported by processor
22:59:48 T:3632   DEBUG: DXVA - checking samples array size using 842094169 render target
22:59:48 T:3632   DEBUG: DXVA - render target not supported by processor
22:59:48 T:3904  NOTICE: Thread DVDPlayer start, auto delete: false
22:59:48 T:3904  NOTICE: Creating InputStream
22:59:48 T:3904   DEBUG: CFileCache::Open - opening <F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi> using cache
22:59:48 T:3904   DEBUG: CUtil::ScanForExternalSubtitles: Searching for subtitles...
22:59:48 T:2760  NOTICE: Thread FileCache start, auto delete: false
22:59:48 T:3904   DEBUG: CUtil::ScanForExternalSubtitles: END (total time: 2 ms)
22:59:48 T:3904  NOTICE: Creating Demuxer
22:59:48 T:3904   DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [avi]
22:59:48 T:2760    INFO: CFileCache::Process - Hit eof.
22:59:48 T:3904   DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
22:59:48 T:3904   DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
22:59:48 T:3904    INFO: ffmpeg[F40]: Input #0, avi, from 'F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi':
22:59:48 T:3904    INFO: ffmpeg[F40]:   Metadata:
22:59:48 T:3904    INFO: ffmpeg[F40]:     encoder         : VirtualDubMod 1.5.10.1 (build 2366/release)
22:59:48 T:3904    INFO: ffmpeg[F40]:   Duration: 00:22:46.43, start: 0.000000, bitrate: 1071 kb/s
22:59:48 T:3904    INFO: ffmpeg[F40]:     Stream #0:0: Video: mpeg4 (Simple Profile) (XVID / 0x44495658), yuv420p, 512x384 [SAR 1:1 DAR 4:3], 932 kb/s, 29.97 fps, 29.97 tbr, 29.97 tbn, 29.97 tbc
22:59:48 T:3904    INFO: ffmpeg[F40]:     Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 44100 Hz, stereo, s16p, 128 kb/s
22:59:48 T:3904   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
22:59:48 T:3904   DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
22:59:48 T:3904   DEBUG: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 2810583 bytes per second
22:59:48 T:3904  NOTICE: Opening stream: 0 source: 256
22:59:48 T:3904  NOTICE: Creating video codec with codec id: 13
22:59:48 T:3904   DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no DXVA:yes iMXVPU:no
22:59:48 T:3904   DEBUG: FactoryCodec - Video:  - Opening
22:59:48 T:3904  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2
22:59:48 T:3904   DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
22:59:48 T:3904  NOTICE: Creating video thread
22:59:48 T:3904  NOTICE: Opening stream: 1 source: 256
22:59:48 T:3904  NOTICE: Finding audio codec for: 86017
22:59:48 T:3148  NOTICE: Thread DVDPlayerVideo start, auto delete: false
22:59:48 T:3148  NOTICE: running thread: video_thread
22:59:48 T:3904   DEBUG: FactoryCodec - Audio: passthrough - Opening
22:59:48 T:3904   DEBUG: FactoryCodec - Audio: passthrough - Failed
22:59:48 T:3904   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
22:59:48 T:3904   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
22:59:48 T:3904  NOTICE: Creating audio thread
22:59:48 T:3904   DEBUG: CEdl::ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi
22:59:48 T:4036  NOTICE: Thread DVDPlayerAudio start, auto delete: false
22:59:48 T:3148   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
22:59:48 T:3904   DEBUG: CApplication::OnPlayBackStarted: play state was 1, starting 1
22:59:48 T:3904   DEBUG: CDVDPlayer::SetCaching - caching state 1
22:59:48 T:4036  NOTICE: running thread: CDVDPlayerAudio::Process()
22:59:48 T:4036   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
22:59:48 T:3904   DEBUG: CDVDPlayer::SetCaching - caching state 3
22:59:48 T:3632   DEBUG: CApplication::PlayFile: OpenFile succeed, play state 2
22:59:48 T:3632   DEBUG: CApplication::OnPlayBackStarted: play state was 2, starting 0
22:59:48 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action doubleclick
22:59:48 T:3904   DEBUG: CDVDPlayer::SetCaching - caching state 0
22:59:48 T:3148    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
22:59:48 T:4036  NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 44100, no pass-through)
22:59:48 T:3632   DEBUG: CGUIInfoManager::SetCurrentMovie(F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi)
22:59:48 T:3148    INFO: ffmpeg[C4C]: [mpeg4] Video uses a non-standard and wasteful way to store B-frames ('packed B-frames'). Consider using a tool like VirtualDub or avidemux to fix it.
22:59:48 T:3632   DEBUG: CVideoDatabase::GetMovieId (F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi), query = select idMovie from movie where idFile=7
22:59:48 T:2224    INFO: CActiveAESink::OpenSink - initialize sink
22:59:48 T:2224   DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up
22:59:48 T:3148   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(33366.700033, 0)
22:59:48 T:3632   DEBUG: CVideoDatabase::GetEpisodeId (F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi), query = select idEpisode from episode where idFile=7
22:59:48 T:4036   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
22:59:48 T:3632   DEBUG: CVideoDatabase::GetMusicVideoId (F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi), query = select idMVideo from musicvideo where idFile=7
22:59:48 T:3148   DEBUG: ffmpeg[C4C]: [src] w:512 h:384 pixfmt:yuv420p tb:100/2997 fr:0/1 sar:1/1 sws_param:
22:59:48 T:3632   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
22:59:48 T:3148  NOTICE:  fps: 29.970030, pwidth: 512, pheight: 384, dwidth: 512, dheight: 384
22:59:48 T:3148   DEBUG: CDVDPlayerVideo::OutputPicture - change configuration. 512x384. framerate: 29.97. format: YV12
22:59:48 T:3148  NOTICE: Display resolution DESKTOP : 1024x768@ 60.00 - Full Screen (16)
22:59:48 T:3148   DEBUG: CWinRenderer::SelectRenderMethod: Requested render method: 0
22:59:48 T:3148   DEBUG: CWinShader::LoadEffect - loading shader special://xbmc/system/shaders/testshader.fx
22:59:48 T:3632   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
22:59:48 T:2224   DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
22:59:48 T:3148   DEBUG: CWinRenderer::SelectRenderMethod: Selected render method 1: Pixel Shaders
22:59:48 T:3148   DEBUG: CXBMCRenderManager::Configure - 3
22:59:48 T:3632   DEBUG: ------ Window Init (DialogKaiToast.xml) ------
22:59:48 T:2224   DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 1311168
22:59:48 T:3632   DEBUG: created video buffer 0
22:59:48 T:3632   DEBUG: created video buffer 1
22:59:48 T:2224   DEBUG: CAESinkDirectSound::Initialize: secondary buffer created
22:59:48 T:3632   DEBUG: created video buffer 2
22:59:48 T:2224   DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
22:59:48 T:2224   DEBUG:   Audio Device    : Headphones (High Definition Audio Device)
22:59:48 T:2224   DEBUG:   Sample Rate     : 44100
22:59:48 T:2224   DEBUG:   Sample Format   : AE_FMT_FLOAT
22:59:48 T:2224   DEBUG:   Bits Per Sample : 32
22:59:48 T:2224   DEBUG:   Valid Bits/Samp : 32
22:59:48 T:2224   DEBUG:   Channel Count   : 2
22:59:48 T:2224   DEBUG:   Block Align     : 8
22:59:48 T:2224   DEBUG:   Avg. Bytes Sec  : 352800
22:59:48 T:2224   DEBUG:   Samples/Block   : 32
22:59:48 T:2224   DEBUG:   Format cBSize   : 22
22:59:48 T:2224   DEBUG:   Channel Layout  : FL,FR
22:59:48 T:2224   DEBUG:   Channel Mask    : 3
22:59:48 T:2224   DEBUG:   Frames          : 661
22:59:48 T:2224   DEBUG:   Frame Samples   : 1322
22:59:48 T:2224   DEBUG:   Frame Size      : 8
22:59:48 T:2224   DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
22:59:48 T:2224   DEBUG:   Output Device : default
22:59:48 T:2224   DEBUG:   Sample Rate   : 44100
22:59:48 T:3632   DEBUG: Activating window ID: 12005
22:59:48 T:3904   DEBUG: CDVDPlayer::HandleMessages - player started 2
22:59:48 T:2224   DEBUG:   Sample Format : AE_FMT_FLOAT
22:59:48 T:2224   DEBUG:   Channel Count : 2
22:59:48 T:2224   DEBUG:   Channel Layout: FL,FR
22:59:48 T:2224   DEBUG:   Frames        : 661
22:59:48 T:2224   DEBUG:   Frame Samples : 1322
22:59:48 T:2224   DEBUG:   Frame Size    : 8
22:59:48 T:3632   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
22:59:48 T:2172   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:59:48 T:3632   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
22:59:48 T:3632    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
22:59:48 T:4036   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(26062.500000, 1)
22:59:48 T:4036   DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError1 - was:5715.927608, should be:5718.669756, error:2.742148
22:59:48 T:3632   DEBUG: CWinShader::LoadEffect - loading shader special://xbmc/system/shaders/yuv2rgb_d3d.fx
22:59:48 T:3904   DEBUG: CDVDPlayer::HandleMessages - player started 1
22:59:48 T:3632   DEBUG: ------ Window Init (VideoOSD.xml) ------
22:59:48 T:3632    INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
22:59:48 T:2224   DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
22:59:48 T:2224 WARNING: CWin32DirectSound::GetSpace - buffer underrun - W:10584, P:0, O:5288.
22:59:52 T:3148   DEBUG: CPullupCorrection: detected pattern of length 1: 33366.70, frameduration: 33366.666667
22:59:54 T:3632   DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
22:59:55 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
22:59:55 T:3632  NOTICE: CDVDPlayer::CloseFile()
22:59:55 T:3632  NOTICE: DVDPlayer: waiting for threads to exit
22:59:55 T:3904  NOTICE: CDVDPlayer::OnExit()
22:59:55 T:3904  NOTICE: Closing stream player 1
22:59:55 T:3904  NOTICE: Waiting for audio thread to exit
22:59:55 T:4036  NOTICE: thread end: CDVDPlayerAudio::OnExit()
22:59:55 T:4036   DEBUG: Thread DVDPlayerAudio 4036 terminating
22:59:55 T:3904  NOTICE: Closing audio device
22:59:55 T:2172   DEBUG: CActiveAE::DiscardStream - audio stream deleted
22:59:55 T:3904  NOTICE: Deleting audio codec
22:59:55 T:2172   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:59:55 T:3904  NOTICE: Closing stream player 2
22:59:55 T:3904  NOTICE: waiting for video thread to exit
22:59:55 T:2172   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:59:55 T:3148  NOTICE: thread end: video_thread
22:59:55 T:3148   DEBUG: Thread DVDPlayerVideo 3148 terminating
22:59:55 T:3904  NOTICE: deleting video codec
22:59:55 T:2760   DEBUG: Thread FileCache 2760 terminating
22:59:55 T:3904   DEBUG: CApplication::OnPlayBackStopped: play state was 2, starting 0
22:59:55 T:3904   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
22:59:55 T:3904   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
22:59:55 T:3904   DEBUG: Thread DVDPlayer 3904 terminating
22:59:55 T:3632  NOTICE: DVDPlayer: finished waiting
22:59:55 T:3632   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
22:59:55 T:3632   DEBUG: ------ Window Deinit (VideoOSD.xml) ------
22:59:55 T:3632   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
22:59:55 T:3632   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
22:59:55 T:3632   DEBUG: ------ Window Init (MyVideoNav.xml) ------
22:59:55 T:3632   DEBUG: CGUIMediaWindow::GetDirectory (F:\)
22:59:55 T:3632   DEBUG:   ParentPath = [F:\]
22:59:55 T:3632   DEBUG: CVideoDatabase::RunQuery took 0 ms for 1 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=3
22:59:55 T:3476  NOTICE: Thread BackgroundLoader start, auto delete: false
22:59:55 T:3476   DEBUG: Thread BackgroundLoader 3476 terminating
22:59:55 T:3632  NOTICE: CDVDPlayer::CloseFile()
22:59:55 T:3632  NOTICE: DVDPlayer: waiting for threads to exit
22:59:55 T:3632  NOTICE: DVDPlayer: finished waiting
22:59:55 T:3632  NOTICE: CDVDPlayer::CloseFile()
22:59:55 T:3632  NOTICE: DVDPlayer: waiting for threads to exit
22:59:55 T:3632  NOTICE: DVDPlayer: finished waiting
22:59:55 T:2136   DEBUG: CSaveFileStateJob::DoWork - Saving file state for video item F:\Trailer Park Boys - S05E03 - The Fuckin' Way She Goes.avi
22:59:57 T:3632    INFO: Python, unloading python shared library because no scripts are running anymore
22:59:57 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
22:59:57 T:3632   DEBUG: Activating window ID: 10000
22:59:57 T:3632   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
22:59:57 T:3632   DEBUG: ------ Window Init (Home.xml) ------
22:59:59 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
22:59:59 T:3632   DEBUG: Activating window ID: 10111
22:59:59 T:3632   DEBUG: ------ Window Init (DialogButtonMenu.xml) ------
22:59:59 T:3632    INFO: Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY
23:00:00 T:3632   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick
23:00:00 T:3632   DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc
23:00:00 T:3632   DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnQuit
23:00:00 T:3632  NOTICE: Storing total System Uptime
23:00:00 T:3632  NOTICE: Saving settings
23:00:00 T:3632  NOTICE: stop all
23:00:00 T:3632  NOTICE: stop player
23:00:00 T:3632    INFO: stopping PVRManager
23:00:00 T:2136   DEBUG: Thread JobWorker 2136 terminating (autodelete)
23:00:00 T:3632   DEBUG: CNetwork::NetworkMessage - Signaling network services to stop
23:00:00 T:3632  NOTICE: ES: Stopping event server
23:00:00 T:3632   DEBUG: CNetwork::NetworkMessage - Waiting for network services to stop
23:00:01 T:932  NOTICE: ES: UDP Event server stopped
23:00:01 T:2188   DEBUG: Thread TCPServer 2188 terminating
23:00:01 T:932   DEBUG: Thread EventServer 932 terminating
23:00:01 T:3864   DEBUG: Thread PeripBusCEC 3864 terminating
23:00:01 T:976   DEBUG: Thread PeripBusUSB 976 terminating
23:00:01 T:3632  NOTICE: stop sap announcement listener
23:00:01 T:3632  NOTICE: clean cached files!
23:00:01 T:3632  NOTICE: unload skin
23:00:01 T:3632    INFO: Unloading old skin ...
23:00:01 T:3632   DEBUG: ------ Window Deinit (Home.xml) ------
23:00:01 T:3632   DEBUG: ------ Window Deinit (Pointer.xml) ------
23:00:01 T:3632   DEBUG: CTextureBundleXBT::Cleanup - Closed bundle
23:00:01 T:3632   DEBUG: Infobool 'player.showcodec' still used by 2 instances
23:00:01 T:3632   DEBUG: ADDON: Stopping service addons.
23:00:01 T:2172   DEBUG: Thread ActiveAE 2172 terminating
23:00:01 T:2224   DEBUG: Thread AESink 2224 terminating
23:00:01 T:3632   DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up
23:00:01 T:3632  NOTICE: stopped
23:00:01 T:3632  NOTICE: destroy
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-win32.xbmc.org has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been uninstalled.'
23:00:01 T:3632   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been uninstalled.'
23:00:01 T:3632    INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 04071080 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
23:00:01 T:3632    INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 03FCB2B8 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
23:00:01 T:3632  NOTICE: closing down remote control service
23:00:01 T:612   DEBUG: Thread RemoteControl 612 terminating
23:00:01 T:3632  NOTICE: unload sections
23:00:01 T:3632   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
23:00:01 T:3632  NOTICE: special://profile/ is mapped to: special://masterprofile/
23:00:01 T:3632   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
23:00:01 T:3632  NOTICE: destroy
23:00:01 T:3632 WARNING: Attempted to remove window 10013 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10014 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10015 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10016 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10017 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10018 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10019 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10021 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10107 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10115 from the window manager when it didn't exist
23:00:01 T:3632 WARNING: Attempted to remove window 10104 from the window manager when it didn't exist
23:00:01 T:3632  NOTICE: closing down remote control service
23:00:01 T:3632  NOTICE: unload sections
23:00:01 T:3632  NOTICE: application stopped...
23:00:01 T:3632   DEBUG: PVRManager - destroyed
23:00:01 T:3632   DEBUG: SECTION:UnloadDll(special://xbmcbin/system/libcurl.dll)
23:00:01 T:3632   ERROR: dllFreeLibrary - Invalid hModule specified
23:00:01 T:3632   DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/cpluff.dll)
23:00:01 T:3632   DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/libcec.dll)
23:00:01 T:3632   DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/ImageLib.dll)


Your <cachemembuffersize> is only set to be 1MB, the default is 20MB.

http://kodi.wiki/view/how-to:modify_the_video_cache

FWIW, this is how mine is set and it works perfectly.

Code:
<network>
    <cachemembuffersize>0</cachemembuffersize>
    <buffermode>1</buffermode>
  </network>
HTPCs: 2 x Chromecast with Google TV
Audio: Pioneer VSX-819HK & S-HS 100 5.1 Speakers
Server: HP Compaq Pro 6300, 4GB RAM, 8.75TB, Bodhi Linux 5.x, NFS, MySQL
Reply
#23
Pretty sure it's been established that this is a well documented issue, and it has nothing to do with your cache settings. Thanks for your input though speedwell68.
Reply
#24
(2015-05-23, 11:17)Blazemonkey Wrote: Pretty sure it's been established that this is a well documented issue, and it has nothing to do with your cache settings. Thanks for your input though speedwell68.

But surely the well documented issue assumes that you haven't reduced your cache to 1/20th of the default minimum.
HTPCs: 2 x Chromecast with Google TV
Audio: Pioneer VSX-819HK & S-HS 100 5.1 Speakers
Server: HP Compaq Pro 6300, 4GB RAM, 8.75TB, Bodhi Linux 5.x, NFS, MySQL
Reply
#25
@speedwell68 by setting cache mode to 0 you have turned the cache OFF for local files. WHich is when this erroneous message tends to be seen.

I turned mine on because when resuming very large 1080p rips it would start playing, then there would be an annoying pause, then play again - with no other netwrok issues on a very reliable gigabit ethernet network. This wasn't always the case (started happenign with Helix IIRC), and there is an unsolved NFS performance issue I believe it relates to, but in general enabling caching gets rid of the problem....the price you pay is this message, which is bogusly raised.

Hopefully, and it sounds like it will, v15 will be better - and if I could get it to migrate my library I'd be trying it out!
Addons I wrote &/or maintain:
OzWeather (Australian BOM weather) | Check Previous Episode | Playback Resumer | Unpause Jumpback | XSqueezeDisplay | (Legacy - XSqueeze & XZen)
Sorry, no help w/out a *full debug log*.
Reply
#26
(2015-05-23, 11:49)speedwell68 Wrote:
(2015-05-23, 11:17)Blazemonkey Wrote: Pretty sure it's been established that this is a well documented issue, and it has nothing to do with your cache settings. Thanks for your input though speedwell68.

But surely the well documented issue assumes that you haven't reduced your cache to 1/20th of the default minimum.

Please remove the large quoted log from your post above.
If I have helped you or increased your knowledge, click the 'thumbs up' button to give thanks :) (People with less than 20 posts won't see the "thumbs up" button.)
Reply
#27
I battle with stuttering video and cache full messages. I an on Mac El Capitan, and I cant find the xml file to edit. Can someone help me please?
Reply
#28
Can someone please help me. My firestick keeps buffering now I've tried to do what it says on Google.
I've downloaded the easy advance settings and done them. It's still buffering. Can someone please go thru step by step with me please to stop it happening I cannot watch anything at all thank you.
My settings are:
Buffer mode 1
Cachemembuffer 5242880
Readbufferfactor 20
Ftpshowcache true
PLEASE HELP
Reply
#29
For 90% of the people who see that message, there is nothing they can do to stop it. The problem is on the other side of the connection.
Reply

Logout Mark Read Team Forum Stats Members Help
Is it possible to use buffering without the annoying "Cache Full" pop up?0