Skip to content

Instantly share code, notes, and snippets.

/gist:535486f65e225972fa4af4559d342b3e Secret

Created March 17, 2018 19:08
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save anonymous/535486f65e225972fa4af4559d342b3e to your computer and use it in GitHub Desktop.
Save anonymous/535486f65e225972fa4af4559d342b3e to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
19:34:59.190 T:139743923149184 NOTICE: special://profile/ is mapped to: special://masterprofile/
19:34:59.190 T:139743923149184 NOTICE: -----------------------------------------------------------------------
19:34:59.190 T:139743923149184 NOTICE: Starting Kodi (17.6 Git:2018-02-12-7e52c1d). Platform: Linux x86 64-bit
19:34:59.190 T:139743923149184 NOTICE: Using Debug Kodi x64 build
19:34:59.190 T:139743923149184 NOTICE: Kodi compiled Mar 17 2018 by GCC 6.3.0 for Linux x86 64-bit version 4.9.82 (264530)
19:34:59.190 T:139743923149184 NOTICE: Running on Debian GNU/Linux 9 (stretch), kernel: Linux x86 64-bit version 4.9.0-4-amd64
19:34:59.190 T:139743923149184 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi
19:34:59.190 T:139743923149184 NOTICE: Host CPU: Intel(R) Core(TM) i7-3615QM CPU @ 2.30GHz, 8 cores available
19:34:59.190 T:139743923149184 NOTICE: special://xbmc/ is mapped to: /usr/local/share/kodi
19:34:59.190 T:139743923149184 NOTICE: special://xbmcbin/ is mapped to: /usr/local/lib/kodi
19:34:59.190 T:139743923149184 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/local/lib/kodi/addons
19:34:59.190 T:139743923149184 NOTICE: special://masterprofile/ is mapped to: /home/bud/.kodi/userdata
19:34:59.190 T:139743923149184 NOTICE: special://envhome/ is mapped to: /home/bud
19:34:59.190 T:139743923149184 NOTICE: special://home/ is mapped to: /home/bud/.kodi
19:34:59.190 T:139743923149184 NOTICE: special://temp/ is mapped to: /home/bud/.kodi/temp
19:34:59.190 T:139743923149184 NOTICE: special://logpath/ is mapped to: /home/bud/.kodi/temp
19:34:59.190 T:139743923149184 NOTICE: The executable running is: /usr/local/lib/kodi/kodi.bin
19:34:59.190 T:139743923149184 NOTICE: Local hostname: bud
19:34:59.190 T:139743923149184 NOTICE: Log File is located: /home/bud/.kodi/temp//kodi.log
19:34:59.190 T:139743923149184 NOTICE: -----------------------------------------------------------------------
19:34:59.192 T:139743923149184 DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.ConsoleKit was not provided by any .service files
19:34:59.193 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:34:59.193 T:139743923149184 DEBUG: DeviceKit.Power: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
19:34:59.193 T:139743923149184 INFO: Selected Logind/UPower as PowerSyscall
19:34:59.221 T:139743923149184 DEBUG: LogindUPowerSyscall - inhibit lock taken, fd 10
19:34:59.222 T:139743923149184 NOTICE: load settings...
19:34:59.228 T:139743923149184 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/settings.xml
19:34:59.231 T:139743923149184 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/linux.xml
19:34:59.233 T:139743923149184 DEBUG: PulseAudio: Context authorizing
19:34:59.233 T:139743923149184 DEBUG: PulseAudio: Context setting name
19:34:59.233 T:139743923149184 DEBUG: PulseAudio: Context ready
19:34:59.234 T:139743438214912 DEBUG: PulseAudio: Found Built-in Audio Digital Stereo (HDMI 3) with devicestring alsa_output.pci-0000_00_1b.0.hdmi-stereo-extra2
19:34:59.234 T:139743923149184 NOTICE: Found 1 Lists of Devices
19:34:59.234 T:139743923149184 NOTICE: Enumerated PULSE devices:
19:34:59.234 T:139743923149184 NOTICE: Device 1
19:34:59.234 T:139743923149184 NOTICE: m_deviceName : Default
19:34:59.234 T:139743923149184 NOTICE: m_displayName : Default
19:34:59.234 T:139743923149184 NOTICE: m_displayNameExtra: Default Output Device (PULSEAUDIO)
19:34:59.234 T:139743923149184 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:34:59.234 T:139743923149184 NOTICE: m_channels : FL,FR
19:34:59.234 T:139743923149184 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
19:34:59.234 T:139743923149184 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
19:34:59.234 T:139743923149184 NOTICE: m_streamTypes : No passthrough capabilities
19:34:59.234 T:139743923149184 NOTICE: Device 2
19:34:59.234 T:139743923149184 NOTICE: m_deviceName : alsa_output.pci-0000_00_1b.0.hdmi-stereo-extra2
19:34:59.234 T:139743923149184 NOTICE: m_displayName : Built-in Audio Digital Stereo (HDMI 3)
19:34:59.234 T:139743923149184 NOTICE: m_displayNameExtra: HDMI / DisplayPort 3 (PULSEAUDIO)
19:34:59.234 T:139743923149184 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:34:59.234 T:139743923149184 NOTICE: m_channels : FL,FR
19:34:59.234 T:139743923149184 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
19:34:59.234 T:139743923149184 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
19:34:59.234 T:139743923149184 NOTICE: m_streamTypes : No passthrough capabilities
19:34:59.236 T:139743923149184 DEBUG: CSkinSettings: no <skinsettings> tag found
19:34:59.236 T:139743923149184 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
19:34:59.236 T:139743923149184 ERROR: Error loading special://profile/advancedsettings.xml, no <advancedsettings> node
19:34:59.236 T:139743923149184 NOTICE: Default Video Player: VideoPlayer
19:34:59.236 T:139743923149184 NOTICE: Default Audio Player: paplayer
19:34:59.236 T:139743923149184 NOTICE: Disabled debug logging due to GUI setting. Level 0.
19:34:59.236 T:139743923149184 NOTICE: Log level changed to "LOG_LEVEL_NORMAL"
19:34:59.236 T:139743923149184 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
19:34:59.237 T:139743923149184 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
19:34:59.237 T:139743923149184 DEBUG: CPlayerCoreConfig::<ctor>: created player VideoPlayer
19:34:59.237 T:139743923149184 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
19:34:59.237 T:139743923149184 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
19:34:59.237 T:139743923149184 NOTICE: Loaded playercorefactory configuration
19:34:59.237 T:139743923149184 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
19:34:59.237 T:139743923149184 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
19:34:59.239 T:139743923149184 INFO: creating subdirectories
19:34:59.239 T:139743923149184 INFO: userdata folder: special://masterprofile/
19:34:59.239 T:139743923149184 INFO: recording folder:
19:34:59.239 T:139743923149184 INFO: screenshots folder:
19:34:59.240 T:139743923149184 NOTICE: Running database version Addons27
19:34:59.240 T:139743923149184 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
19:34:59.240 T:139743923149184 DEBUG: Loading: /usr/local/lib/kodi/system/libcpluff-x86_64-linux.so
19:34:59.249 T:139743923149184 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/local/lib/kodi/addons: No such file or directory'
19:34:59.326 T:139743438214912 DEBUG: Thread JobWorker start, auto delete: true
19:34:59.329 T:139743923149184 NOTICE: ADDONS: Using repository repository.xbmc.org
19:34:59.330 T:139743427643136 DEBUG: Thread ActiveAE start, auto delete: false
19:34:59.330 T:139743419250432 DEBUG: Thread AESink start, auto delete: false
19:34:59.331 T:139743419250432 INFO: CActiveAESink::OpenSink - initialize sink
19:34:59.331 T:139743419250432 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
19:34:59.332 T:139743419250432 DEBUG: PulseAudio: Context authorizing
19:34:59.332 T:139743419250432 DEBUG: PulseAudio: Context setting name
19:34:59.332 T:139743419250432 DEBUG: PulseAudio: Context ready
19:34:59.333 T:139743419250432 DEBUG: PulseAudio: Stream ready
19:34:59.333 T:139743419250432 NOTICE: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms
19:34:59.333 T:139743419250432 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
19:34:59.333 T:139743419250432 DEBUG: Output Device : Default
19:34:59.333 T:139743419250432 DEBUG: Sample Rate : 44100
19:34:59.333 T:139743419250432 DEBUG: Sample Format : AE_FMT_FLOAT
19:34:59.333 T:139743419250432 DEBUG: Channel Count : 2
19:34:59.333 T:139743419250432 DEBUG: Channel Layout: FL,FR
19:34:59.333 T:139743419250432 DEBUG: Frames : 2205
19:34:59.333 T:139743419250432 DEBUG: Frame Size : 8
19:34:59.335 T:139743127922432 DEBUG: Thread RemoteControl start, auto delete: false
19:34:59.335 T:139743127922432 INFO: LIRC Process: using: /dev/lircd
19:34:59.335 T:139743127922432 INFO: LIRC Connect: connect failed: No such file or directory
19:34:59.335 T:139743127922432 INFO: CRemoteControl::Process - failed to connect to LIRC, will keep retrying every 5 seconds
19:34:59.336 T:139743923149184 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
19:34:59.337 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
19:34:59.337 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
19:34:59.337 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
19:34:59.337 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
19:34:59.337 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
19:34:59.337 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
19:34:59.338 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
19:34:59.338 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
19:34:59.338 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
19:34:59.338 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
19:34:59.338 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
19:34:59.339 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
19:34:59.339 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
19:34:59.339 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
19:34:59.339 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
19:34:59.339 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
19:34:59.339 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
19:34:59.340 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
19:34:59.340 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
19:34:59.340 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
19:34:59.340 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
19:34:59.340 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
19:34:59.341 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
19:34:59.341 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
19:34:59.341 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
19:34:59.341 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
19:34:59.341 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
19:34:59.341 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
19:34:59.342 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
19:34:59.342 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
19:34:59.342 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
19:34:59.342 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
19:34:59.342 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
19:34:59.343 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
19:34:59.343 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
19:34:59.343 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded
19:34:59.343 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
19:34:59.343 T:139743923149184 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
19:34:59.344 T:139743923149184 DEBUG: UDisks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
19:34:59.344 T:139743923149184 DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
19:34:59.345 T:139743923149184 DEBUG: Selected UDev as storage provider
19:34:59.583 T:139743923149184 INFO: Available videomodes (xrandr):
19:34:59.583 T:139743923149184 INFO: Output 'HDMI3' has 41 modes
19:34:59.583 T:139743923149184 INFO: ID:0x4c Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xe8 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xe9 Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xec Name:1920x1080 Refresh:30.000000 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xed Name:1920x1080 Refresh:25.000000 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xee Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xf0 Name:1920x1080 Refresh:29.970100 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xf1 Name:1920x1080 Refresh:23.976080 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xea Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xeb Name:1920x1080i Refresh:25.000000 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xef Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xf2 Name:1680x1050 Refresh:59.883251 Width:1680 Height:1050
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.118421
19:34:59.583 T:139743923149184 INFO: ID:0xf3 Name:1600x900 Refresh:60.000000 Width:1600 Height:900
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xf4 Name:1280x1024 Refresh:75.024673 Width:1280 Height:1024
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.431579
19:34:59.583 T:139743923149184 INFO: ID:0xf5 Name:1280x1024 Refresh:60.019741 Width:1280 Height:1024
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.431579
19:34:59.583 T:139743923149184 INFO: ID:0xf6 Name:1440x900 Refresh:59.901459 Width:1440 Height:900
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.118421
19:34:59.583 T:139743923149184 INFO: ID:0xf7 Name:1366x768 Refresh:59.789539 Width:1366 Height:768
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006088
19:34:59.583 T:139743923149184 INFO: ID:0xf8 Name:1280x800 Refresh:59.909550 Width:1280 Height:800
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.118421
19:34:59.583 T:139743923149184 INFO: ID:0xf9 Name:1152x864 Refresh:75.000000 Width:1152 Height:864
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0xfa Name:1280x720 Refresh:60.000000 Width:1280 Height:720
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xfb Name:1280x720 Refresh:50.000000 Width:1280 Height:720
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xfc Name:1280x720 Refresh:59.940201 Width:1280 Height:720
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.006579
19:34:59.583 T:139743923149184 INFO: ID:0xfd Name:1024x768 Refresh:75.028580 Width:1024 Height:768
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0xfe Name:1024x768 Refresh:70.069359 Width:1024 Height:768
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0xff Name:1024x768 Refresh:60.003841 Width:1024 Height:768
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x100 Name:832x624 Refresh:74.551270 Width:832 Height:624
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x101 Name:800x600 Refresh:72.187569 Width:800 Height:600
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x102 Name:800x600 Refresh:75.000000 Width:800 Height:600
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x103 Name:800x600 Refresh:60.316540 Width:800 Height:600
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x104 Name:720x576 Refresh:50.000000 Width:720 Height:576
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.431579
19:34:59.583 T:139743923149184 INFO: ID:0x105 Name:720x576i Refresh:25.000000 Width:720 Height:576
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.431579
19:34:59.583 T:139743923149184 INFO: ID:0x106 Name:720x480 Refresh:60.000000 Width:720 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.192982
19:34:59.583 T:139743923149184 INFO: ID:0x107 Name:720x480 Refresh:59.940060 Width:720 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.192982
19:34:59.583 T:139743923149184 INFO: ID:0x108 Name:720x480i Refresh:30.001110 Width:720 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.192982
19:34:59.583 T:139743923149184 INFO: ID:0x109 Name:720x480i Refresh:29.970030 Width:720 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.192982
19:34:59.583 T:139743923149184 INFO: ID:0x10a Name:640x480 Refresh:75.000000 Width:640 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x10b Name:640x480 Refresh:72.808800 Width:640 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x10c Name:640x480 Refresh:66.666672 Width:640 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x10d Name:640x480 Refresh:60.000000 Width:640 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x10e Name:640x480 Refresh:59.940479 Width:640 Height:480
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 1.342105
19:34:59.583 T:139743923149184 INFO: ID:0x10f Name:720x400 Refresh:70.081657 Width:720 Height:400
19:34:59.583 T:139743923149184 INFO: Pixel Ratio: 0.994152
19:34:59.583 T:139743923149184 NOTICE: Checking resolution 16
19:34:59.640 T:139743923149184 NOTICE: Using visual 0x20
19:34:59.645 T:139743923149184 INFO: GL: Maximum texture width: 8192
19:34:59.645 T:139743923149184 DEBUG: EGL_EXTENSIONS: EGL_CHROMIUM_sync_control EGL_EXT_create_context_robustness EGL_EXT_image_dma_buf_import EGL_KHR_create_context EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_image_dma_buf_export EGL_NOK_swap_region EGL_NOK_texture_from_pixmap EGL_NV_post_sub_buffer EGL_WL_bind_wayland_display
19:34:59.645 T:139743923149184 NOTICE: GL_VENDOR = Intel Open Source Technology Center
19:34:59.645 T:139743923149184 NOTICE: GL_RENDERER = Mesa DRI Intel(R) Ivybridge Mobile
19:34:59.645 T:139743923149184 NOTICE: GL_VERSION = 3.0 Mesa 13.0.6
19:34:59.645 T:139743923149184 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
19:34:59.645 T:139743923149184 NOTICE: GL_EXTENSIONS = GL_ARB_multisample GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_copy_texture GL_EXT_polygon_offset GL_EXT_subtexture GL_EXT_texture_object GL_EXT_vertex_array GL_EXT_compiled_vertex_array GL_EXT_texture GL_EXT_texture3D GL_IBM_rasterpos_clip GL_ARB_point_parameters GL_EXT_draw_range_elements GL_EXT_packed_pixels GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_separate_specular_color GL_EXT_texture_edge_clamp GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_ARB_framebuffer_sRGB GL_ARB_multitexture GL_EXT_framebuffer_sRGB GL_IBM_multimode_draw_arrays GL_IBM_texture_mirrored_repeat GL_3DFX_texture_compression_FXT1 GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_transpose_matrix GL_EXT_blend_func_separate GL_EXT_fog_coord GL_EXT_multi_draw_arrays GL_EXT_secondary_color GL_EXT_texture_env_add GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod_bias GL_INGR_blend_func_separate GL_NV_blend_square GL_NV_light_max_exponent GL_NV_texgen_reflection GL_NV_texture_env_combine4 GL_S3_s3tc GL_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_compression_s3tc GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_ARB_depth_texture GL_ARB_occlusion_query GL_ARB_shadow GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_mirrored_repeat GL_ARB_window_pos GL_EXT_stencil_two_side GL_EXT_texture_cube_map GL_NV_depth_clamp GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_shader GL_ARB_shader_objects GL_ARB_vertex_program GL_ARB_vertex_shader GL_ATI_draw_buffers GL_ATI_texture_env_combine3 GL_ATI_texture_float GL_EXT_shadow_funcs GL_EXT_stencil_wrap GL_MESA_pack_invert GL_NV_primitive_restart GL_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel GL_ARB_occlusion_query2 GL_ARB_point_sprite GL_ARB_shading_language_100 GL_ARB_sync GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ATI_blend_equation_separate GL_EXT_blend_equation_separate GL_OES_read_format GL_ARB_color_buffer_float GL_ARB_pixel_buffer_object GL_ARB_texture_compression_rgtc GL_ARB_texture_float GL_ARB_texture_rectangle GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_rectangle GL_EXT_texture_sRGB GL_EXT_texture_shared_exponent GL_ARB_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_packed_depth_stencil GL_APPLE_object_purgeable GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_AMD_performance_monitor GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_conservative_depth GL_AMD_draw_buffers_blend GL_AMD_seamless_cubemap_per_texture GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_gather GL_ARB_texture_multisample GL_ARB_texture_query_lod GL_ARB_texture_rgb10_a2ui GL_ARB_uniform_buffer_object GL_ARB_vertex_type_2_10_10_10_rev GL_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_NV_texture_barrier GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_texture_compression_bptc GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_shader_atomic_counters GL_ARB_shader_image_load_store GL_ARB_shading_language_420pack GL_ARB_shading_language_packing GL_ARB_texture_storage GL_ARB_transform_feedback_instanced GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_transform_feedback GL_AMD_shader_trinary_minmax GL_ARB_ES3_compatibility GL_ARB_arrays_of_arrays GL_ARB_clear_buffer_object GL_ARB_compute_shader GL_ARB_copy_image GL_ARB_explicit_uniform_location GL_ARB_framebuffer_no_attachments GL_ARB_invalidate_subdata GL_ARB_program_interface_query GL_ARB_shader_image_size GL_ARB_shader_storage_buffer_object GL_ARB_texture_query_levels GL_ARB_texture_storage_multisample GL_ARB_texture_view GL_ARB_vertex_attrib_binding GL_KHR_debug GL_KHR_robustness GL_ARB_buffer_storage GL_ARB_clear_texture GL_ARB_internalformat_query2 GL_ARB_multi_bind GL_ARB_seamless_cubemap_per_texture GL_ARB_shader_draw_parameters GL_ARB_texture_mirror_clamp_to_edge GL_ARB_vertex_type_10f_11f_11f_rev GL_EXT_shader_integer_mix GL_INTEL_performance_query GL_ARB_clip_control GL_ARB_conditional_render_inverted GL_ARB_cull_distance GL_ARB_derivative_control GL_ARB_get_texture_sub_image GL_ARB_pipeline_statistics_query GL_ARB_shader_texture_image_samples GL_ARB_texture_barrier GL_EXT_polygon_offset_clamp GL_KHR_blend_equation_advanced GL_KHR_context_flush_control GL_ARB_shader_atomic_counter_ops GL_ARB_shader_clock GL_EXT_shader_samples_identical GL_MESA_shader_integer_functions
19:34:59.645 T:139743923149184 INFO: GL: Maximum texture width: 8192
19:34:59.694 T:139743923149184 INFO: GL: Enabling VSYNC
19:34:59.699 T:139743923149184 INFO: load keymapping
19:34:59.700 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
19:34:59.700 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
19:34:59.700 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
19:34:59.701 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
19:34:59.701 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/joystick.xml
19:34:59.702 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
19:34:59.704 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/mouse.xml
19:34:59.704 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/remote.xml
19:34:59.706 T:139743923149184 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
19:34:59.707 T:139743923149184 INFO: Loading special://xbmc/system/Lircmap.xml
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'mceusb'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'XboxDVDDongle'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'anysee'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'iMON-PAD'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'MCE_via_iMON'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'TwinHanRemote'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'linux-input-layer'
19:34:59.707 T:139743923149184 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'mediacenter'
19:34:59.707 T:139743923149184 INFO: * Adding remote mapping for device 'devinput'
19:34:59.708 T:139743923149184 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
19:34:59.708 T:139743923149184 INFO: GUI format 1920x1080, Display 1920x1080@ 60.00 - Full Screen
19:34:59.708 T:139743923149184 DEBUG: guilib: Fill viewport on change for solving rendering passes
19:34:59.708 T:139743923149184 INFO: CLangInfo: loading resource.language.en_gb language information...
19:34:59.708 T:139743923149184 DEBUG: trying to set locale to en_DE.UTF-8
19:34:59.708 T:139743923149184 INFO: global locale set to C
19:34:59.708 T:139743923149184 INFO: CLangInfo: loading resource.language.en_gb language strings...
19:34:59.714 T:139743923149184 DEBUG: LocalizeStrings: loaded 3780 strings from file resource://resource.language.en_gb/strings.po
19:34:59.715 T:139743923149184 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/local/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po
19:34:59.716 T:139743923149184 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/local/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po
19:34:59.716 T:139743923149184 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/local/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
19:34:59.716 T:139743923149184 DEBUG: LocalizeStrings: loaded 13 strings from file /home/bud/.kodi/addons/metadata.album.universal/resources/language/English/strings.po
19:34:59.716 T:139743923149184 DEBUG: LocalizeStrings: loaded 26 strings from file /home/bud/.kodi/addons/metadata.artists.universal/resources/language/English/strings.po
19:34:59.717 T:139743923149184 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /home/bud/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
19:34:59.717 T:139743923149184 DEBUG: LocalizeStrings: loaded 8 strings from file /home/bud/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
19:34:59.717 T:139743923149184 DEBUG: LocalizeStrings: loaded 10 strings from file /home/bud/.kodi/addons/metadata.tvdb.com/resources/language/English/strings.po
19:34:59.717 T:139743923149184 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/local/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
19:34:59.718 T:139743923149184 DEBUG: LocalizeStrings: loaded 6 strings from file /home/bud/.kodi/addons/weather.yahoo/resources/language/English/strings.po
19:34:59.718 T:139743923149184 DEBUG: LocalizeStrings: loaded 21 strings from file /home/bud/.kodi/addons/service.xbmc.versioncheck/resources/language/English/strings.po
19:34:59.718 T:139743923149184 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
19:34:59.718 T:139743923149184 DEBUG: LoadMappings - loaded node "CEC Adapter"
19:34:59.718 T:139743923149184 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
19:34:59.718 T:139743923149184 DEBUG: LoadMappings - loaded node "iMON HID device"
19:34:59.718 T:139743923149184 DEBUG: LoadMappings - loaded node "WETEK Play remote"
19:34:59.718 T:139743923149184 DEBUG: LoadMappings - loaded node ""
19:34:59.719 T:139743923149184 DEBUG: CPeripheralBusUSB - initialised udev monitor
19:34:59.719 T:139743923149184 DEBUG: SECTION:LoadDLL(libcec.so.4)
19:34:59.719 T:139743923149184 DEBUG: Loading: libcec.so.4
19:34:59.724 T:139742720378624 DEBUG: Thread PeripBusUSBUdev start, auto delete: false
19:34:59.724 T:139742711985920 DEBUG: Thread PeripBusCEC start, auto delete: false
19:34:59.724 T:139742703593216 DEBUG: Thread PeripBusAddon start, auto delete: false
19:34:59.724 T:139743923149184 DEBUG: SECTION:LoadDLL(libcurl-gnutls.so.4)
19:34:59.724 T:139742695200512 DEBUG: Thread PeripEventScanner start, auto delete: false
19:34:59.724 T:139743923149184 DEBUG: Loading: libcurl-gnutls.so.4
19:34:59.728 T:139743438214912 NOTICE: Running database version Addons27
19:34:59.728 T:139743438214912 DEBUG: Initialize, updating databases...
19:34:59.729 T:139743438214912 NOTICE: Running database version ViewModes6
19:34:59.729 T:139743438214912 NOTICE: Running database version Textures13
19:34:59.730 T:139743438214912 NOTICE: Running database version MyMusic60
19:34:59.733 T:139743438214912 NOTICE: Running database version MyVideos107
19:34:59.733 T:139743438214912 NOTICE: Running database version TV29
19:34:59.734 T:139743438214912 NOTICE: Running database version Epg11
19:34:59.735 T:139743438214912 DEBUG: Initialize, updating databases... DONE
19:34:59.735 T:139743923149184 NOTICE: start dvd mediatype detection
19:34:59.735 T:139743922886400 DEBUG: Thread DetectDVDMedia start, auto delete: false
19:34:59.735 T:139743922886400 DEBUG: Compiled with libcdio Version 0.83
19:34:59.736 T:139743923149184 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
19:34:59.736 T:139743922886400 DEBUG: Thread DetectDVDMedia 139743922886400 terminating
19:34:59.744 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
19:34:59.745 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
19:34:59.748 T:139743923149184 INFO: Unloading old skin ...
19:34:59.748 T:139743923149184 INFO: load skin from: /usr/local/share/kodi/addons/skin.estuary (version: 1.9.16)
19:34:59.748 T:139743923149184 INFO: load fonts for skin...
19:34:59.749 T:139743923149184 INFO: Loading fonts from /usr/local/share/kodi/addons/skin.estuary/xml/Font.xml
19:34:59.754 T:139743923149184 DEBUG: LocalizeStrings: loaded 132 strings from file /usr/local/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po
19:34:59.755 T:139743923149184 INFO: Loading skin includes from /usr/local/share/kodi/addons/skin.estuary/xml/Includes.xml
19:34:59.766 T:139743923149184 INFO: load new skin...
19:34:59.767 T:139743923149184 INFO: Loading user windows, path /usr/local/share/kodi/addons/skin.estuary/xml
19:34:59.768 T:139743923149184 DEBUG: Load Skin XML: 1.95ms
19:34:59.768 T:139743923149184 INFO: initialize new skin...
19:34:59.768 T:139743923149184 DEBUG: guilib: Fill viewport on change for solving rendering passes
19:34:59.768 T:139743923149184 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
19:34:59.769 T:139743923149184 DEBUG: Load Pointer.xml: 0.21ms
19:34:59.769 T:139743923149184 DEBUG: OpenBundle - Opened bundle /usr/local/share/kodi/addons/skin.estuary/media/Textures.xbt
19:34:59.769 T:139743923149184 DEBUG: Alloc resources: 1.05ms (0.22 ms skin load)
19:34:59.770 T:139743923149184 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
19:34:59.770 T:139743923149184 DEBUG: Load DialogVolumeBar.xml: 0.42ms
19:34:59.770 T:139743923149184 DEBUG: Alloc resources: 0.46ms (0.43 ms skin load)
19:34:59.770 T:139743923149184 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
19:34:59.770 T:139743923149184 DEBUG: Load DialogNotification.xml: 0.34ms
19:34:59.770 T:139743923149184 DEBUG: Alloc resources: 0.36ms (0.36 ms skin load)
19:34:59.770 T:139743923149184 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
19:34:59.772 T:139743923149184 DEBUG: Load DialogSeekBar.xml: 2.09ms
19:34:59.772 T:139743923149184 DEBUG: Alloc resources: 2.14ms (2.11 ms skin load)
19:34:59.772 T:139743923149184 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
19:34:59.773 T:139743923149184 DEBUG: Load DialogBusy.xml: 0.40ms
19:34:59.773 T:139743923149184 DEBUG: Alloc resources: 0.42ms (0.42 ms skin load)
19:34:59.773 T:139743923149184 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
19:34:59.773 T:139743923149184 DEBUG: Load DialogExtendedProgressBar.xml: 0.56ms
19:34:59.773 T:139743923149184 DEBUG: Alloc resources: 0.57ms (0.57 ms skin load)
19:34:59.774 T:139743923149184 DEBUG: Alloc resources: 0.00ms (0.00 ms skin load)
19:34:59.774 T:139743923149184 INFO: Loading resource://resource.uisounds.kodi/sounds.xml
19:34:59.782 T:139743923149184 INFO: skin loaded...
19:34:59.782 T:139743923149184 DEBUG: Activating window ID: 12997
19:34:59.782 T:139743923149184 DEBUG: ------ Window Init () ------
19:34:59.782 T:139743923149184 DEBUG: Alloc resources: 0.00ms (0.00 ms skin load)
19:34:59.782 T:139743923149184 INFO: load splash image: /usr/local/share/kodi/media/Splash.png
19:34:59.788 T:139743923149184 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
19:34:59.788 T:139743923149184 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
19:34:59.788 T:139743923149184 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
19:34:59.788 T:139743923149184 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
19:34:59.799 T:139743923149184 INFO: JSONRPC v8.0.0: Successfully initialized
19:34:59.799 T:139743923149184 DEBUG: ADDON: Starting service addons.
19:34:59.799 T:139742259189504 DEBUG: Thread LanguageInvoker start, auto delete: false
19:34:59.799 T:139743923149184 DEBUG: Activating window ID: 12999
19:34:59.799 T:139742259189504 INFO: initializing python engine.
19:34:59.799 T:139743923149184 DEBUG: ------ Window Init (Startup.xml) ------
19:34:59.799 T:139743923149184 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
19:34:59.799 T:139743923149184 DEBUG: Load Startup.xml: 0.08ms
19:34:59.799 T:139743923149184 DEBUG: Alloc resources: 0.10ms (0.09 ms skin load)
19:34:59.799 T:139743923149184 DEBUG: Activating window ID: 10000
19:34:59.799 T:139743923149184 DEBUG: ------ Window Deinit (Startup.xml) ------
19:34:59.799 T:139743923149184 DEBUG: ------ Window Init (Home.xml) ------
19:34:59.799 T:139743923149184 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
19:34:59.842 T:139742259189504 DEBUG: CPythonInvoker(0, /home/bud/.kodi/addons/service.xbmc.versioncheck/service.py): start processing
19:34:59.844 T:139743923149184 DEBUG: Load Home.xml: 44.44ms
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
19:34:59.844 T:139742247999232 DEBUG: Thread JobWorker start, auto delete: true
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
19:34:59.844 T:139743923149184 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
19:34:59.844 T:139742239606528 DEBUG: Thread JobWorker start, auto delete: true
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[library://video/]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: CDirectoryProvider[favourites://]: refreshing..
19:34:59.845 T:139743923149184 DEBUG: Alloc resources: 45.91ms (44.67 ms skin load)
19:34:59.846 T:139743923149184 DEBUG: ContextMenuManager: addon menus reloaded.
19:34:59.846 T:139743923149184 INFO: removing tempfiles
19:34:59.846 T:139743923149184 NOTICE: UpdateLibraries: Starting video library startup scan
19:34:59.846 T:139743923149184 NOTICE: UpdateLibraries: Starting music library startup scan
19:34:59.850 T:139743923149184 DEBUG: ADDON: Starting service addons.
19:34:59.850 T:139742229899008 DEBUG: Thread MusicInfoScanner start, auto delete: false
19:34:59.850 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnScanStarted from xbmc
19:34:59.850 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 32, from xbmc, message OnScanStarted
19:34:59.851 T:139742247999232 DEBUG: RunQuery took 2 ms for 0 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
19:34:59.854 T:139743923149184 DEBUG: CRepositoryUpdater: previous update at 17-03-2018 19:32:58, next at 18-03-2018 19:32:58
19:34:59.854 T:139743923149184 NOTICE: initialize done
19:34:59.854 T:139743923149184 NOTICE: Running the application...
19:34:59.854 T:139742221068032 DEBUG: Thread Timer start, auto delete: false
19:34:59.854 T:139743923149184 DEBUG: no profile autoexec.py (/home/bud/.kodi/userdata/autoexec.py) found, skipping
19:34:59.854 T:139743923149184 DEBUG: NetworkMessage - Starting network services
19:34:59.856 T:139743923149184 INFO: CZeroconfAvahi::clientCallback: avahi server not available. But may become later...
19:34:59.856 T:139743923149184 NOTICE: starting zeroconf publishing
19:34:59.856 T:139743923149184 NOTICE: CWebServer[8080]: Started
19:34:59.856 T:139743923149184 NOTICE: starting upnp client
19:34:59.856 T:139743923149184 NOTICE: starting upnp server
19:34:59.858 T:139743923149184 NOTICE: starting upnp renderer
19:34:59.860 T:139741701850880 DEBUG: Thread EventServer start, auto delete: false
19:34:59.860 T:139741701850880 NOTICE: ES: Starting UDP Event server on port 9777
19:34:59.860 T:139743923149184 ERROR: JSONRPC Server: Failed to connect to sdpd
19:34:59.860 T:139741701850880 NOTICE: UDP: Listening on port 9777 (ipv6 : true)
19:34:59.860 T:139743923149184 INFO: JSONRPC Server: Successfully initialized
19:34:59.860 T:139741693458176 DEBUG: Thread TCPServer start, auto delete: false
19:34:59.860 T:139743923149184 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
19:34:59.860 T:139743923149184 DEBUG: Window DialogExtendedProgressBar.xml was already loaded
19:34:59.860 T:139743923149184 DEBUG: Alloc resources: 0.00ms
19:34:59.860 T:139742229899008 DEBUG: Process - Starting scan
19:34:59.860 T:139741685065472 DEBUG: Thread MusicFileCounter start, auto delete: false
19:34:59.860 T:139741685065472 DEBUG: Thread MusicFileCounter 139741685065472 terminating
19:34:59.860 T:139742229899008 NOTICE: My Music: Scanning for music info using worker thread, operation took 00:00
19:34:59.860 T:139742229899008 DEBUG: Process - Finished scan
19:34:59.860 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnScanFinished from xbmc
19:34:59.860 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 32, from xbmc, message OnScanFinished
19:34:59.860 T:139742229899008 DEBUG: Thread MusicInfoScanner 139742229899008 terminating
19:34:59.861 T:139742239606528 DEBUG: RunQuery took 9 ms for 131 items query: select * from movie_view WHERE ((movie_view.dateAdded > '1900-01-01')) AND ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
19:34:59.868 T:139742247999232 DEBUG: RunQuery took 7 ms for 131 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
19:34:59.873 T:139743438214912 DEBUG: RunQuery took 7 ms for 131 items query: select * from movie_view
19:34:59.882 T:139742259189504 DEBUG: -->Python Interpreter Initialized<--
19:34:59.882 T:139742259189504 DEBUG: CPythonInvoker(0, /home/bud/.kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/bud/.kodi/addons/service.xbmc.versioncheck/service.py"
19:34:59.882 T:139742259189504 DEBUG: CPythonInvoker(0, /home/bud/.kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/bud/.kodi/addons/service.xbmc.versioncheck:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
19:34:59.882 T:139742259189504 DEBUG: CPythonInvoker(0, /home/bud/.kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /home/bud/.kodi/addons/service.xbmc.versioncheck
19:34:59.882 T:139742259189504 DEBUG: CPythonInvoker(0, /home/bud/.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
19:34:59.883 T:139742239606528 DEBUG: RunQuery took 2 ms for 17 items query: SELECT genre.genre_id, genre.name, count(1), count(files.playCount) FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN movie_view ON genre_link.media_id = movie_view.idMovie AND genre_link.media_type='movie' JOIN files ON files.idFile = movie_view.idFile GROUP BY genre.genre_id
19:34:59.892 T:139742239606528 DEBUG: RunQuery took 3 ms for 60 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet
19:34:59.902 T:139742259189504 DEBUG: Version Check: Version 0.3.26 started
19:34:59.908 T:139743438214912 DEBUG: RunQuery took 9 ms for 1 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00
19:34:59.909 T:139741685065472 DEBUG: Thread JobWorker start, auto delete: true
19:34:59.921 T:139742247999232 DEBUG: RunQuery took 12 ms for 40 items query: SELECT * FROM tvshow_view WHERE ((tvshow_view.watchedcount = 0)) AND ((tvshow_view.totalCount > 0))
19:34:59.923 T:139743923149184 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------
19:34:59.927 T:139742239606528 DEBUG: RunQuery took 21 ms for 1372 items query: select * from episode_view WHERE ((episode_view.dateAdded > '1900-01-01')) AND ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
19:35:00.054 T:139742247999232 DEBUG: RunQuery took 4 ms for 18 items query: SELECT genre.genre_id, genre.name FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN tvshow_view ON genre_link.media_id = tvshow_view.idShow AND genre_link.media_type='tvshow' GROUP BY genre.genre_id
19:35:00.062 T:139742247999232 DEBUG: RunQuery took 4 ms for 20 items query: SELECT studio.studio_id, studio.name FROM studio JOIN studio_link ON studio.studio_id = studio_link.studio_id JOIN tvshow_view ON studio_link.media_id = tvshow_view.idShow AND studio_link.media_type='tvshow' GROUP BY studio.studio_id
19:35:00.071 T:139742247999232 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
19:35:00.071 T:139742247999232 DEBUG: Getting Directory (special://profile/playlists/video)
19:35:00.071 T:139742247999232 DEBUG: Getting Directory (special://profile/playlists/mixed)
19:35:00.072 T:139742247999232 DEBUG: CMultiPathDirectory::MergeItems, items = 0
19:35:00.072 T:139742247999232 DEBUG: CFavourites::Load - no system favourites found, skipping
19:35:00.072 T:139743438214912 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
19:35:00.075 T:139743438214912 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder
19:35:00.075 T:139743438214912 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
19:35:00.077 T:139743438214912 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (bud) port:8080
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (bud) port:8080
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (bud) port:9777
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (bud) port:9090
19:35:00.080 T:139741685065472 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
19:35:00.080 T:139741685065472 INFO: WEATHER: Downloading weather
19:35:00.081 T:139741376886528 DEBUG: Thread LanguageInvoker start, auto delete: false
19:35:00.081 T:139741376886528 INFO: initializing python engine.
19:35:00.081 T:139741376886528 DEBUG: CPythonInvoker(1, /home/bud/.kodi/addons/weather.yahoo/default.py): start processing
19:35:00.083 T:139743438214912 DEBUG: RunQuery took 3 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10
19:35:00.094 T:139743438214912 DEBUG: RunQuery took 8 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10
19:35:00.095 T:139743923149184 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
19:35:00.095 T:139743923149184 DEBUG: Window DialogExtendedProgressBar.xml was already loaded
19:35:00.095 T:139743923149184 DEBUG: Alloc resources: 0.01ms
19:35:00.098 T:139742247999232 NOTICE: VideoInfoScanner: Starting scan ..
19:35:00.098 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnScanStarted from xbmc
19:35:00.098 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnScanStarted
19:35:00.098 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/Movies/' due to no change (fasthash)
19:35:00.099 T:139743438214912 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10
19:35:00.100 T:139743438214912 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
19:35:00.121 T:139743438214912 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
19:35:00.126 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Modern.Family/' due to no change
19:35:00.130 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.Last.Man.On.Earth/' due to no change
19:35:00.131 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.Last.Ship/' due to no change
19:35:00.133 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Men.at.Work/' due to no change
19:35:00.134 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/BoJack.Horseman/' due to no change
19:35:00.137 T:139743438214912 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
19:35:00.139 T:139743438214912 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder
19:35:00.139 T:139743438214912 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
19:35:00.139 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Its.Always.Sunny.in.Philadelphia/' due to no change
19:35:00.140 T:139743438214912 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
19:35:00.140 T:139743438214912 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
19:35:00.141 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Californication/' due to no change
19:35:00.143 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.X-Files/' due to no change
19:35:00.144 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/New.Girl/' due to no change
19:35:00.147 T:139741376886528 DEBUG: -->Python Interpreter Initialized<--
19:35:00.147 T:139741376886528 DEBUG: CPythonInvoker(1, /home/bud/.kodi/addons/weather.yahoo/default.py): the source file to load is "/home/bud/.kodi/addons/weather.yahoo/default.py"
19:35:00.147 T:139741376886528 DEBUG: CPythonInvoker(1, /home/bud/.kodi/addons/weather.yahoo/default.py): setting the Python path to /home/bud/.kodi/addons/weather.yahoo:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
19:35:00.147 T:139741376886528 DEBUG: CPythonInvoker(1, /home/bud/.kodi/addons/weather.yahoo/default.py): entering source directory /home/bud/.kodi/addons/weather.yahoo
19:35:00.147 T:139741376886528 DEBUG: CPythonInvoker(1, /home/bud/.kodi/addons/weather.yahoo/default.py): instantiating addon using automatically obtained id of "weather.yahoo" dependent on version 2.25.0 of the xbmc.python api
19:35:00.148 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.Walking.Dead/' due to no change
19:35:00.152 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Game.of.Thrones/' due to no change
19:35:00.155 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.Big.Bang.Theory/' due to no change
19:35:00.157 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Fear.the.Walking.Dead/' due to no change
19:35:00.158 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Westworld/' due to no change
19:35:00.159 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Mr.Robot/' due to no change
19:35:00.163 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.King.of.Queens/' due to no change
19:35:00.165 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Adventure.Time/' due to no change
19:35:00.166 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Better.Call.Saul/' due to no change
19:35:00.167 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/American.Dad/' due to no change
19:35:00.168 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Futurama/' due to no change
19:35:00.269 T:139741376886528 DEBUG: weather.yahoo: version 4.3.1 started: ['/home/bud/.kodi/addons/weather.yahoo/default.py', '1']
19:35:00.270 T:139741376886528 DEBUG: weather.yahoo: weather location: 784794
19:35:00.361 T:139743923149184 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
19:35:00.451 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Talking.Dead/' due to no change
19:35:00.479 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Suits/' due to no change
19:35:00.505 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Rick.and.Morty/' due to no change
19:35:00.510 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.Americans/' due to no change
19:35:00.512 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Community/' due to no change
19:35:00.518 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Rules.of.Engagement/' due to no change
19:35:00.561 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.Sopranos/' due to no change
19:35:00.582 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Wilfred.US/' due to no change
19:35:00.583 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Gomorrah/' due to no change
19:35:00.585 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Mushi-Shi/' due to no change
19:35:00.587 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Regular.Show/' due to no change
19:35:00.589 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Justified/' due to no change
19:35:00.591 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Person.of.Interest/' due to no change
19:35:00.592 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Limitless/' due to no change
19:35:00.594 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/House.of.Cards.US/' due to no change
19:35:00.624 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Sherlock/' due to no change
19:35:00.642 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/True.Detective/' due to no change
19:35:00.645 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/How.I.Met.Your.Mother/' due to no change
19:35:00.647 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/This.Is.Us/' due to no change
19:35:00.649 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Sense8/' due to no change
19:35:00.652 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Louie.2010/' due to no change
19:35:00.655 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Breaking.Bad/' due to no change
19:35:00.658 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Family.Guy/' due to no change
19:35:00.660 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/The.Simpsons/' due to no change
19:35:00.661 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Silicon.Valley/' due to no change
19:35:00.666 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Arrested.Development/' due to no change
19:35:00.667 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/Halt.and.Catch.Fire/' due to no change
19:35:00.669 T:139742247999232 DEBUG: VideoInfoScanner: Skipping dir '/data/TV/White.Collar/' due to no change
19:35:00.744 T:139742247999232 NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:00
19:35:00.744 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnScanFinished from xbmc
19:35:00.744 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnScanFinished
19:35:00.745 T:139742239606528 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
19:35:00.753 T:139742239606528 DEBUG: RunQuery took 4 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10
19:35:00.754 T:139741376886528 DEBUG: weather.yahoo: forecast data: {"query":{"count":1,"created":"2018-03-17T18:35:00Z","lang":"en-US","results":{"channel":{"units":{"distance":"km","pressure":"mb","speed":"km/h","temperature":"C"},"title":"Yahoo! Weather - Zurich, ZH, CH","link":"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-784794/","description":"Yahoo! Weather for Zurich, ZH, CH","language":"en-us","lastBuildDate":"Sat, 17 Mar 2018 07:35 PM CET","ttl":"60","location":{"city":"Zurich","country":"Switzerland","region":" ZH"},"wind":{"chill":"36","direction":"60","speed":"17.70"},"atmosphere":{"humidity":"88","pressure":"31730.47","rising":"0","visibility":"23.50"},"astronomy":{"sunrise":"6:34 am","sunset":"6:35 pm"},"image":{"title":"Yahoo! Weather","width":"142","height":"18","link":"http://weather.yahoo.com","url":"http://l.yimg.com/a/i/brand/purplelogo//uh/us/news-wea.gif"},"item":{"title":"Conditions for Zurich, ZH, CH at 07:00 PM CET","lat":"47.38427","long":"8.52959","link":"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-784794/","pubDate":"Sat, 17 Mar 2018 07:00 PM CET","condition":{"code":"11","date":"Sat, 17 Mar 2018 07:00 PM CET","temp":"4","text":"Showers"},"forecast":[{"code":"5","date":"17 Mar 2018","day":"Sat","high":"7","low":"0","text":"Rain And Snow"},{"code":"16","date":"18 Mar 2018","day":"Sun","high":"3","low":"0","text":"Snow"},{"code":"26","date":"19 Mar 2018","day":"Mon","high":"1","low":"-2","text":"Cloudy"},{"code":"30","date":"20 Mar 2018","day":"Tue","high":"3","low":"-4","text":"Partly Cloudy"},{"code":"30","date":"21 Mar 2018","day":"Wed","high":"2","low":"-6","text":"Partly Cloudy"},{"code":"30","date":"22 Mar 2018","day":"Thu","high":"3","low":"-3","text":"Partly Cloudy"},{"code":"30","date":"23 Mar 2018","day":"Fri","high":"5","low":"-2","text":"Partly Cloudy"},{"code":"28","date":"24 Mar 2018","day":"Sat","high":"7","low":"0","text":"Mostly Cloudy"},{"code":"28","date":"25 Mar 2018","day":"Sun","high":"8","low":"0","text":"Mostly Cloudy"},{"code":"39","date":"26 Mar 2018","day":"Mon","high":"10","low":"2","text":"Scattered Showers"}],"description":"<![CDATA[<img src=\"http://l.yimg.com/a/i/us/we/52/11.gif\"/>\n<BR />\n<b>Current Conditions:</b>\n<BR />Showers\n<BR />\n<BR />\n<b>Forecast:</b>\n<BR /> Sat - Rain And Snow. High: 7Low: 0\n<BR /> Sun - Snow. High: 3Low: 0\n<BR /> Mon - Cloudy. High: 1Low: -2\n<BR /> Tue - Partly Cloudy. High: 3Low: -4\n<BR /> Wed - Partly Cloudy. High: 2Low: -6\n<BR />\n<BR />\n<a href=\"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-784794/\">Full Forecast at Yahoo! Weather</a>\n<BR />\n<BR />\n<BR />\n]]>","guid":{"isPermaLink":"false"}}}}}}
19:35:00.757 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
19:35:00.757 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
19:35:00.757 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
19:35:00.757 T:139743923149184 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
19:35:00.757 T:139743923149184 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing..
19:35:00.759 T:139741376886528 DEBUG: weather.yahoo: available locations: 1
19:35:00.759 T:139741376886528 DEBUG: weather.yahoo: finished
19:35:00.759 T:139741376886528 INFO: CPythonInvoker(1, /home/bud/.kodi/addons/weather.yahoo/default.py): script successfully run
19:35:00.765 T:139743438214912 DEBUG: RunQuery took 6 ms for 131 items query: select * from movie_view WHERE ((movie_view.dateAdded > '1900-01-01')) AND ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
19:35:00.765 T:139741376886528 INFO: Python script stopped
19:35:00.765 T:139741376886528 DEBUG: Thread LanguageInvoker 139741376886528 terminating
19:35:00.768 T:139742239606528 DEBUG: RunQuery took 11 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10
19:35:00.774 T:139742239606528 DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10
19:35:00.775 T:139742239606528 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
19:35:00.776 T:139742239606528 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
19:35:00.784 T:139743438214912 DEBUG: RunQuery took 4 ms for 131 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
19:35:00.786 T:139741685065472 DEBUG: POParser: loaded 130 weather tokens
19:35:00.797 T:139741685065472 DEBUG: RunQuery took 7 ms for 131 items query: select * from movie_view
19:35:00.804 T:139743438214912 DEBUG: RunQuery took 3 ms for 17 items query: SELECT genre.genre_id, genre.name, count(1), count(files.playCount) FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN movie_view ON genre_link.media_id = movie_view.idMovie AND genre_link.media_type='movie' JOIN files ON files.idFile = movie_view.idFile GROUP BY genre.genre_id
19:35:00.933 T:139741685065472 DEBUG: RunQuery took 3 ms for 60 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet
19:35:00.961 T:139742239606528 DEBUG: DoWork - took 104 ms to load special://masterprofile/Thumbnails/5/55815505.jpg
19:35:00.994 T:139743923149184 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------
19:35:04.954 T:139742259189504 DEBUG: Version Check: Version installed {u'major': 17, u'tag': u'stable', u'minor': 6, u'revision': u'2018-02-12-7e52c1d'}
19:35:04.954 T:139742259189504 DEBUG: Version Check: Version available {u'major': u'17', u'extrainfo': u'final', u'tagversion': u'', u'tag': u'stable', u'addon_support': u'yes', u'minor': u'6', u'revision': u'20171114-a9a7a20'}
19:35:04.954 T:139742259189504 DEBUG: Version Check: There is no newer stable available
19:35:04.954 T:139742259189504 INFO: CPythonInvoker(0, /home/bud/.kodi/addons/service.xbmc.versioncheck/service.py): script successfully run
19:35:04.960 T:139742259189504 INFO: Python script stopped
19:35:04.960 T:139742259189504 DEBUG: Thread LanguageInvoker 139742259189504 terminating
19:35:15.018 T:139743923149184 INFO: Python, unloading python shared library because no scripts are running anymore
19:35:15.022 T:139743923149184 DEBUG: UnloadExtensionLibs, clearing python extension libraries
19:35:30.912 T:139742247999232 DEBUG: Thread JobWorker 139742247999232 terminating (autodelete)
19:35:30.940 T:139741685065472 DEBUG: Thread JobWorker 139741685065472 terminating (autodelete)
19:35:30.948 T:139743438214912 DEBUG: Thread JobWorker 139743438214912 terminating (autodelete)
19:35:30.961 T:139742239606528 DEBUG: Thread JobWorker 139742239606528 terminating (autodelete)
19:36:41.436 T:139741693458176 DEBUG: JSONRPC Server: New connection detected
19:36:41.436 T:139741693458176 INFO: JSONRPC Server: New connection added
19:36:41.534 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:42.077 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:42.077 T:139743923149184 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
19:36:42.077 T:139743419250432 INFO: CActiveAESink::OpenSink - initialize sink
19:36:42.077 T:139743419250432 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
19:36:42.078 T:139743923149184 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
19:36:42.078 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
19:36:42.078 T:139742247999232 DEBUG: Thread JobWorker start, auto delete: true
19:36:42.078 T:139743923149184 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
19:36:42.079 T:139743923149184 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
19:36:42.079 T:139741685065472 DEBUG: Thread JobWorker start, auto delete: true
19:36:42.079 T:139743923149184 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
19:36:42.079 T:139743419250432 DEBUG: PulseAudio: Context authorizing
19:36:42.079 T:139743438214912 DEBUG: Thread JobWorker start, auto delete: true
19:36:42.079 T:139743419250432 DEBUG: PulseAudio: Context setting name
19:36:42.080 T:139743419250432 DEBUG: PulseAudio: Context ready
19:36:42.083 T:139743419250432 DEBUG: PulseAudio: Stream ready
19:36:42.083 T:139743419250432 NOTICE: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms
19:36:42.084 T:139743419250432 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
19:36:42.084 T:139743419250432 DEBUG: Output Device : Default
19:36:42.084 T:139743419250432 DEBUG: Sample Rate : 44100
19:36:42.084 T:139743419250432 DEBUG: Sample Format : AE_FMT_FLOAT
19:36:42.084 T:139743419250432 DEBUG: Channel Count : 2
19:36:42.084 T:139743419250432 DEBUG: Channel Layout: FL,FR
19:36:42.084 T:139743419250432 DEBUG: Frames : 2205
19:36:42.084 T:139743419250432 DEBUG: Frame Size : 8
19:36:42.093 T:139742247999232 DEBUG: RunQuery took 7 ms for 1 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00
19:36:42.097 T:139742798628608 DEBUG: Thread JobWorker start, auto delete: true
19:36:42.103 T:139743438214912 DEBUG: RunQuery took 13 ms for 40 items query: SELECT * FROM tvshow_view WHERE ((tvshow_view.watchedcount = 0)) AND ((tvshow_view.totalCount > 0))
19:36:42.104 T:139742247999232 DEBUG: RunQuery took 5 ms for 18 items query: SELECT genre.genre_id, genre.name FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN tvshow_view ON genre_link.media_id = tvshow_view.idShow AND genre_link.media_type='tvshow' GROUP BY genre.genre_id
19:36:42.104 T:139741685065472 DEBUG: RunQuery took 19 ms for 1372 items query: select * from episode_view WHERE ((episode_view.dateAdded > '1900-01-01')) AND ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
19:36:42.115 T:139742247999232 DEBUG: RunQuery took 5 ms for 20 items query: SELECT studio.studio_id, studio.name FROM studio JOIN studio_link ON studio.studio_id = studio_link.studio_id JOIN tvshow_view ON studio_link.media_id = tvshow_view.idShow AND studio_link.media_type='tvshow' GROUP BY studio.studio_id
19:36:42.129 T:139743923149184 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultStudio.png'
19:36:42.676 T:139743923149184 DEBUG: Previous line repeats 5 times.
19:36:42.676 T:139743923149184 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
19:36:42.678 T:139743923149184 DEBUG: Activating window ID: 10025
19:36:43.009 T:139743923149184 DEBUG: ------ Window Deinit (Home.xml) ------
19:36:43.016 T:139743923149184 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:36:43.016 T:139743923149184 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
19:36:43.045 T:139743923149184 DEBUG: Load MyVideoNav.xml: 29.54ms
19:36:43.045 T:139743923149184 DEBUG: CDirectoryProvider[]: refreshing..
19:36:43.045 T:139742798628608 DEBUG: Previous line repeats 6 times.
19:36:43.046 T:139742798628608 ERROR: GetDirectory - Error getting
19:36:43.046 T:139743923149184 ERROR: Previous line repeats 6 times.
19:36:43.046 T:139743923149184 DEBUG: Alloc resources: 30.29ms (29.61 ms skin load)
19:36:43.046 T:139743923149184 DEBUG: CGUIMediaWindow::GetDirectory (videodb://tvshows/titles/)
19:36:43.046 T:139743923149184 DEBUG: ParentPath = [videodb://tvshows/titles/]
19:36:43.051 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:43.056 T:139743923149184 DEBUG: RunQuery took 8 ms for 47 items query: SELECT * FROM tvshow_view WHERE totalCount IS NOT NULL AND totalCount > 0
19:36:43.059 T:139742780368640 DEBUG: Thread BackgroundLoader start, auto delete: false
19:36:43.072 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:43.086 T:139742780368640 DEBUG: Thread BackgroundLoader 139742780368640 terminating
19:36:44.421 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:44.426 T:139741685065472 ERROR: DoWork - Direct texture file loading failed for resource://resource.images.studios.white/FOX (US).png
19:36:44.426 T:139741685065472 DEBUG: GetImageHash - unable to stat url resource://resource.images.studios.white/FOX (US).png
19:36:44.583 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:44.627 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:44.627 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:44.628 T:139742247999232 ERROR: DoWork - Direct texture file loading failed for resource://resource.images.studios.white/FXX.png
19:36:44.628 T:139742247999232 DEBUG: GetImageHash - unable to stat url resource://resource.images.studios.white/FXX.png
19:36:44.814 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:44.815 T:139741685065472 ERROR: DoWork - Direct texture file loading failed for resource://resource.images.studios.white/USA Network.png
19:36:44.815 T:139741685065472 DEBUG: GetImageHash - unable to stat url resource://resource.images.studios.white/USA Network.png
19:36:45.461 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:45.461 T:139742247999232 ERROR: DoWork - Direct texture file loading failed for resource://resource.images.studios.white/HBO.png
19:36:45.461 T:139742247999232 DEBUG: GetImageHash - unable to stat url resource://resource.images.studios.white/HBO.png
19:36:45.779 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:45.781 T:139741685065472 ERROR: DoWork - Direct texture file loading failed for resource://resource.images.studios.white/AMC.png
19:36:45.781 T:139741685065472 DEBUG: GetImageHash - unable to stat url resource://resource.images.studios.white/AMC.png
19:36:45.943 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:45.944 T:139742247999232 ERROR: DoWork - Direct texture file loading failed for resource://resource.images.studios.white/HBO.png
19:36:45.944 T:139742247999232 DEBUG: GetImageHash - unable to stat url resource://resource.images.studios.white/HBO.png
19:36:46.100 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:46.671 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:46.671 T:139743923149184 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
19:36:46.673 T:139743438214912 ERROR: DoWork - Direct texture file loading failed for resource://resource.images.studios.white/AMC.png
19:36:46.673 T:139743438214912 DEBUG: GetImageHash - unable to stat url resource://resource.images.studios.white/AMC.png
19:36:47.027 T:139743923149184 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
19:36:47.028 T:139743923149184 DEBUG: CGUIMediaWindow::GetDirectory (videodb://tvshows/titles/8/)
19:36:47.028 T:139743923149184 DEBUG: ParentPath = [videodb://tvshows/titles/]
19:36:47.095 T:139742247999232 DEBUG: RunQuery took 64 ms for 8 items query: SELECT * FROM season_view WHERE season_view.idShow = 8
19:36:47.097 T:139742247999232 DEBUG: RunQuery took 1 ms for 0 items query: select * from movie_view join movielinktvshow on movielinktvshow.idMovie=movie_view.idMovie WHERE movielinktvshow.idShow = 8
19:36:47.102 T:139743923149184 DEBUG: WindowVideoNav::GetDirectory
19:36:47.147 T:139742754076416 DEBUG: Thread BackgroundLoader start, auto delete: false
19:36:47.153 T:139742754076416 DEBUG: Thread BackgroundLoader 139742754076416 terminating
19:36:47.616 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:49.437 T:139743923149184 DEBUG: Previous line repeats 3 times.
19:36:49.437 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:50.407 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:50.407 T:139743923149184 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
19:36:50.667 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:50.726 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:50.726 T:139743923149184 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
19:36:50.727 T:139743923149184 DEBUG: CGUIMediaWindow::GetDirectory (videodb://tvshows/titles/8/8/?tvshowid=8)
19:36:50.727 T:139743923149184 DEBUG: ParentPath = [videodb://tvshows/titles/8/]
19:36:50.731 T:139743438214912 DEBUG: RunQuery took 1 ms for 17 items query: select * from episode_view WHERE (episode_view.idShow = 8) AND ((episode_view.c12 = 8 or (episode_view.c12 = 0 and (episode_view.c15 = 0 or episode_view.c15 = 8))))
19:36:50.734 T:139743923149184 DEBUG: WindowVideoNav::GetDirectory
19:36:50.747 T:139742745683712 DEBUG: Thread BackgroundLoader start, auto delete: false
19:36:50.754 T:139742745683712 DEBUG: Thread BackgroundLoader 139742745683712 terminating
19:36:52.187 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:52.658 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:52.658 T:139743923149184 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
19:36:53.543 T:139743923149184 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
19:36:53.705 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:54.182 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:54.182 T:139743923149184 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
19:36:54.561 T:139743923149184 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
19:36:54.563 T:139743923149184 DEBUG: OnPlayMedia /data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv
19:36:54.563 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:36:54.563 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:36:54.565 T:139743923149184 DEBUG: Loading settings for /data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv
19:36:54.566 T:139743923149184 DEBUG: CPlayerCoreFactory::GetPlayers(/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv)
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:36:54.566 T:139743923149184 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
19:36:54.566 T:139743923149184 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:36:54.566 T:139743923149184 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
19:36:54.566 T:139743923149184 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:36:54.566 T:139743923149184 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:36:54.566 T:139743923149184 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:36:54.568 T:139743923149184 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
19:36:54.568 T:139743923149184 NOTICE: VideoPlayer: Opening: /data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv
19:36:54.568 T:139743923149184 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:36:54.568 T:139743923149184 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:36:54.574 T:139743923149184 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
19:36:54.574 T:139742745683712 DEBUG: Thread VideoPlayer start, auto delete: false
19:36:54.582 T:139742745683712 NOTICE: Creating InputStream
19:36:54.593 T:139742745683712 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
19:36:54.597 T:139742745683712 INFO: ScanPathsForAssociatedItems: found associated file /data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.en.srt
19:36:54.598 T:139742745683712 DEBUG: ScanForExternalSubtitles: END (total time: 5 ms)
19:36:54.598 T:139742745683712 DEBUG: GetExternalStreamDetailsFromFilename - Language = 'eng' / Name = '(External)' / Flag = '0' from /data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.en.srt
19:36:54.598 T:139742745683712 NOTICE: Creating Demuxer
19:36:54.670 T:139742745683712 DEBUG: Open - probing detected format [matroska,webm]
19:36:54.678 T:139742745683712 DEBUG: Open - avformat_find_stream_info starting
19:36:54.694 T:139742745683712 DEBUG: Open - av_find_stream_info finished
19:36:54.694 T:139742745683712 NOTICE: Opening stream: 0 source: 256
19:36:54.694 T:139742745683712 NOTICE: Creating video codec with codec id: 28
19:36:54.694 T:139742745683712 DEBUG: FactoryCodec - Video: - Opening
19:36:54.694 T:139742745683712 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
19:36:54.694 T:139742745683712 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
19:36:54.694 T:139742745683712 DEBUG: FactoryCodec - Video: ff-h264 - Opened
19:36:54.694 T:139742745683712 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
19:36:54.694 T:139742745683712 NOTICE: Creating video thread
19:36:54.694 T:139742745683712 NOTICE: Opening stream: 1 source: 256
19:36:54.694 T:139742745683712 NOTICE: Finding audio codec for: 86018
19:36:54.694 T:139742745683712 DEBUG: FactoryCodec - Audio: passthrough - Opening
19:36:54.694 T:139742745683712 DEBUG: FactoryCodec - Audio: passthrough - Failed
19:36:54.694 T:139742745683712 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
19:36:54.694 T:139742754076416 DEBUG: Thread VideoPlayerVideo start, auto delete: false
19:36:54.694 T:139742754076416 NOTICE: running thread: video_thread
19:36:54.695 T:139742745683712 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
19:36:54.695 T:139742745683712 NOTICE: Creating audio thread
19:36:54.695 T:139742745683712 NOTICE: Opening stream: 0 source: 1024
19:36:54.695 T:139742780368640 DEBUG: Thread VideoPlayerAudio start, auto delete: false
19:36:54.695 T:139742780368640 NOTICE: running thread: CVideoPlayerAudio::Process()
19:36:54.719 T:139742745683712 DEBUG: ReadEditDecisionLists - Assuming 1080i interlaced content. Adjusted frames per second from 30.303 to 15.152
19:36:54.719 T:139742745683712 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv
19:36:54.719 T:139742745683712 DEBUG: OnPlayBackStarted: play state was 1, starting 1
19:36:54.719 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 2
19:36:54.719 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:36:54.719 T:139743923149184 DEBUG: PlayFile: OpenFile succeed, play state 2
19:36:54.719 T:139743923149184 DEBUG: OnPlayBackStarted: play state was 2, starting 0
19:36:54.719 T:139742754076416 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
19:36:54.719 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:36:54.721 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1920x1080)
19:36:54.721 T:139742754076416 NOTICE: VDPAU::Open: required extension GL_NV_vdpau_interop not found
19:36:54.721 T:139742754076416 NOTICE: (VDPAU) Close
19:36:54.721 T:139742754076416 DEBUG: VAAPI - open decoder
19:36:54.726 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:36:54.726 T:139742780368640 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
19:36:54.726 T:139742780368640 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback
19:36:54.729 T:139743419250432 INFO: CActiveAESink::OpenSink - initialize sink
19:36:54.776 T:139742754076416 DEBUG: VAAPI - initialize version 0.39
19:36:54.776 T:139742754076416 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Ivybridge Mobile - 1.7.3
19:36:54.776 T:139742754076416 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
19:36:54.776 T:139742754076416 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
19:36:54.776 T:139742754076416 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
19:36:54.776 T:139742754076416 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
19:36:54.776 T:139742754076416 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 0
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 1
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 13
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 6
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 7
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 16
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 8
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 9
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 10
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile -1
19:36:54.776 T:139742754076416 DEBUG: VAAPI - profile 12
19:36:54.776 T:139740975761152 DEBUG: Thread Vaapi-Output start, auto delete: false
19:36:54.776 T:139740975761152 NOTICE: COutput::OnStartup: Output Thread created
19:36:54.776 T:139742798628608 DEBUG: CGUIInfoManager::SetCurrentMovie(/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv)
19:36:54.776 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:36:54.776 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:36:54.776 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:36:54.780 T:139742798628608 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:36:54.780 T:139740975761152 DEBUG: VAAPI::COutput::CreateEGLContext - created context
19:36:54.780 T:139740975761152 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
19:36:54.780 T:139742798628608 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:36:54.780 T:139740975761152 DEBUG: Loading: /usr/local/lib/kodi/system/libsse4-x86_64-linux.so
19:36:54.787 T:139740975761152 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
19:36:54.787 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
19:36:54.795 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:36:54.801 T:139742754076416 DEBUG: Previous line repeats 2 times.
19:36:54.801 T:139742754076416 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 30.30. format: VAAPI
19:36:54.803 T:139743923149184 DEBUG: DeleteRenderer - deleting renderer
19:36:54.803 T:139743923149184 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:36:54.806 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:36:54.806 T:139743923149184 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
19:36:54.806 T:139743923149184 DEBUG: GL: Requested render method: 0
19:36:54.806 T:139743923149184 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12_RRG
19:36:54.806 T:139743923149184 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
19:36:54.807 T:139743923149184 DEBUG: GL: Vertex Shader compilation log:
19:36:54.807 T:139743923149184 DEBUG: GL: Vertex Shader compiled successfully
19:36:54.808 T:139743923149184 DEBUG: GL: Pixel Shader compilation log:
19:36:54.808 T:139743923149184 DEBUG: GL: Fragment Shader compiled successfully
19:36:54.812 T:139743923149184 DEBUG: GL: Vertex Shader compilation log:
19:36:54.812 T:139743923149184 DEBUG: GL: Vertex Shader compiled successfully
19:36:54.812 T:139743923149184 DEBUG: GL: Pixel Shader compilation log:
19:36:54.812 T:139743923149184 DEBUG: GL: Fragment Shader compiled successfully
19:36:54.813 T:139743923149184 NOTICE: GL: NPOT texture support detected
19:36:54.813 T:139743923149184 NOTICE: GL: Using GL_ARB_pixel_buffer_object
19:36:54.813 T:139743923149184 NOTICE: Using GL_TEXTURE_2D
19:36:54.813 T:139743923149184 DEBUG: CRenderManager::Configure - 4
19:36:54.814 T:139743923149184 DEBUG: GL: Requested render method: 0
19:36:54.814 T:139743923149184 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12_RRG
19:36:54.814 T:139743923149184 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
19:36:54.814 T:139743923149184 DEBUG: GL: Vertex Shader compilation log:
19:36:54.814 T:139743923149184 DEBUG: GL: Vertex Shader compiled successfully
19:36:54.815 T:139743923149184 DEBUG: GL: Pixel Shader compilation log:
19:36:54.815 T:139743923149184 DEBUG: GL: Fragment Shader compiled successfully
19:36:54.816 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 2
19:36:54.819 T:139743923149184 NOTICE: GL: NPOT texture support detected
19:36:54.819 T:139743923149184 NOTICE: GL: Using GL_ARB_pixel_buffer_object
19:36:54.824 T:139743923149184 DEBUG: Activating window ID: 12005
19:36:54.824 T:139743923149184 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:36:54.825 T:139743923149184 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:36:54.825 T:139743923149184 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
19:36:54.826 T:139743923149184 DEBUG: Load VideoFullScreen.xml: 0.71ms
19:36:54.826 T:139743923149184 DEBUG: Alloc resources: 0.73ms (0.73 ms skin load)
19:36:54.947 T:139743419250432 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
19:36:54.948 T:139743419250432 DEBUG: PulseAudio: Context authorizing
19:36:54.949 T:139743419250432 DEBUG: PulseAudio: Context setting name
19:36:54.949 T:139743419250432 DEBUG: PulseAudio: Context ready
19:36:54.949 T:139743419250432 DEBUG: PULSE does not support format: AE_FMT_FLOATP - will fallback to AE_FMT_FLOAT
19:36:54.950 T:139743419250432 DEBUG: PulseAudio: Stream ready
19:36:54.950 T:139743419250432 NOTICE: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms
19:36:54.950 T:139743419250432 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
19:36:54.950 T:139743419250432 DEBUG: Output Device : Default
19:36:54.950 T:139743419250432 DEBUG: Sample Rate : 44100
19:36:54.950 T:139743419250432 DEBUG: Sample Format : AE_FMT_FLOAT
19:36:54.950 T:139743419250432 DEBUG: Channel Count : 2
19:36:54.950 T:139743419250432 DEBUG: Channel Layout: FL,FR
19:36:54.950 T:139743419250432 DEBUG: Frames : 2205
19:36:54.950 T:139743419250432 DEBUG: Frame Size : 8
19:36:54.951 T:139743427643136 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:36:54.959 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 1
19:36:54.959 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 3
19:36:54.959 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:36:54.959 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 0
19:36:54.959 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:36:54.959 T:139742745683712 DEBUG: VideoPlayer::Sync - Audio - pts: 115000.000000, cache: 289344.376785, totalcache: 550000.011921
19:36:54.959 T:139742745683712 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
19:36:54.959 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-174344.376785)
19:36:54.959 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:36:54.959 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-174344.376785)
19:36:54.959 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:36:54.959 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:36:54.960 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:36:55.070 T:139743427643136 DEBUG: Previous line repeats 1 times.
19:36:55.070 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of -24.346769, start adjusting
19:36:55.070 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.346769 below threshold of 30.000000
19:36:55.112 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33363
19:36:55.225 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:55.252 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:36:55.252 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:36:55.253 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:36:56.876 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:56.908 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:36:56.908 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:36:56.908 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:36:57.102 T:139742780368640 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-12747.987726, adjusted:-12747.987726
19:36:58.452 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:36:58.511 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:36:58.511 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:36:58.511 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:36:58.992 T:139742754076416 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
19:37:00.143 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:00.190 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:00.190 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:00.190 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:01.726 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:01.773 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:01.773 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:01.773 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:02.608 T:139743923149184 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward
19:37:02.608 T:139743923149184 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:37:02.608 T:139743923149184 DEBUG: Window DialogSeekBar.xml was already loaded
19:37:02.608 T:139743923149184 DEBUG: Alloc resources: 0.01ms
19:37:02.608 T:139743923149184 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
19:37:02.609 T:139743923149184 INFO: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: KEEP_IN_MEMORY
19:37:02.611 T:139743923149184 DEBUG: Load Custom_1109_TopBarOverlay.xml: 2.20ms
19:37:02.611 T:139743923149184 DEBUG: Alloc resources: 2.24ms (2.23 ms skin load)
19:37:03.308 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:03.332 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:03.332 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:03.332 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:03.358 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
19:37:03.358 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
19:37:03.367 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 2
19:37:03.367 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:03.367 T:139742745683712 DEBUG: demuxer seek to: 18220.000000
19:37:03.379 T:139742745683712 DEBUG: SeekTime - seek ended up on time 20020
19:37:03.379 T:139742745683712 DEBUG: demuxer seek to: 18220.000000, success
19:37:03.379 T:139742745683712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
19:37:03.403 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:03.433 T:139742780368640 DEBUG: CDVDAudio::Flush - flush audio stream
19:37:03.433 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:03.433 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:03.433 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:03.450 T:139742754076416 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
19:37:03.450 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:03.450 T:139742754076416 DEBUG: VAAPI::Release pre-cleanup
19:37:03.453 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
19:37:03.453 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1920x1080)
19:37:03.453 T:139742754076416 NOTICE: VDPAU::Open: required extension GL_NV_vdpau_interop not found
19:37:03.453 T:139742754076416 NOTICE: (VDPAU) Close
19:37:03.453 T:139742754076416 DEBUG: VAAPI - open decoder
19:37:03.453 T:139741669582592 DEBUG: Thread Vaapi-Output start, auto delete: false
19:37:03.453 T:139741669582592 NOTICE: COutput::OnStartup: Output Thread created
19:37:03.457 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 1
19:37:03.461 T:139741669582592 DEBUG: VAAPI::COutput::CreateEGLContext - created context
19:37:03.469 T:139741669582592 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
19:37:03.469 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
19:37:03.471 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:03.473 T:139742754076416 WARNING: CVideoSurfaces::ClearReference - surface invalid
19:37:03.473 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:03.491 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:37:03.492 T:139743923149184 NOTICE: VAAPI::Close
19:37:03.492 T:139740975761152 NOTICE: COutput::OnExit: Output Thread terminated
19:37:03.492 T:139740975761152 DEBUG: Thread Vaapi-Output 139740975761152 terminating
19:37:03.492 T:139743923149184 DEBUG: VAAPI::FiniVAAPIOutput destroying 2 video surfaces
19:37:03.495 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 2
19:37:03.495 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 3
19:37:03.495 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:03.495 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 0
19:37:03.495 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:03.495 T:139742745683712 DEBUG: VideoPlayer::Sync - Audio - pts: 20294000.000000, cache: 278639.465570, totalcache: 550000.011921
19:37:03.495 T:139742745683712 DEBUG: VideoPlayer::Sync - Video - pts: 20020000.000000, cache: 50000.000000, totalcache: 100000.000000
19:37:03.495 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(19920000.000000)
19:37:03.495 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(19920000.000000)
19:37:03.495 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:03.495 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:03.495 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:03.495 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:03.642 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 20.873759, start adjusting
19:37:03.642 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 0.873759 below threshold of 30.000000
19:37:03.658 T:139743923149184 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward
19:37:04.408 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
19:37:04.408 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
19:37:04.411 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 2
19:37:04.411 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:04.411 T:139742745683712 DEBUG: demuxer seek to: 30835.000000
19:37:04.411 T:139742745683712 DEBUG: SeekTime - seek ended up on time 32031
19:37:04.411 T:139742745683712 DEBUG: demuxer seek to: 30835.000000, success
19:37:04.411 T:139742745683712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
19:37:04.419 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:04.441 T:139743923149184 DEBUG: OnKey: yellow (0xe5) pressed, action is PlayerProcessInfo
19:37:04.442 T:139743923149184 DEBUG: Activating window ID: 10116
19:37:04.442 T:139743923149184 DEBUG: ------ Window Init (DialogPlayerProcessInfo.xml) ------
19:37:04.442 T:139743923149184 INFO: Loading skin file: DialogPlayerProcessInfo.xml, load type: KEEP_IN_MEMORY
19:37:04.445 T:139743923149184 DEBUG: Load DialogPlayerProcessInfo.xml: 3.41ms
19:37:04.445 T:139743923149184 DEBUG: Alloc resources: 3.58ms (3.45 ms skin load)
19:37:04.478 T:139742780368640 DEBUG: CDVDAudio::Flush - flush audio stream
19:37:04.478 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:04.861 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:04.887 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:04.887 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:04.887 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:04.924 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:04.925 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:04.925 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:04.986 T:139742754076416 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
19:37:04.987 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:04.987 T:139742754076416 DEBUG: VAAPI::Release pre-cleanup
19:37:04.990 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
19:37:04.990 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1920x1080)
19:37:04.990 T:139742754076416 NOTICE: VDPAU::Open: required extension GL_NV_vdpau_interop not found
19:37:04.990 T:139742754076416 NOTICE: (VDPAU) Close
19:37:04.990 T:139742754076416 DEBUG: VAAPI - open decoder
19:37:04.990 T:139740975761152 DEBUG: Thread Vaapi-Output start, auto delete: false
19:37:04.990 T:139740975761152 NOTICE: COutput::OnStartup: Output Thread created
19:37:04.995 T:139740975761152 DEBUG: VAAPI::COutput::CreateEGLContext - created context
19:37:05.001 T:139740975761152 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
19:37:05.001 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
19:37:05.002 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:05.003 T:139742754076416 WARNING: CVideoSurfaces::ClearReference - surface invalid
19:37:05.003 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:05.010 T:139742745683712 DEBUG: Previous line repeats 1 times.
19:37:05.010 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 1
19:37:05.012 T:139743923149184 NOTICE: VAAPI::Close
19:37:05.013 T:139741669582592 NOTICE: COutput::OnExit: Output Thread terminated
19:37:05.013 T:139741669582592 DEBUG: Thread Vaapi-Output 139741669582592 terminating
19:37:05.013 T:139743923149184 DEBUG: VAAPI::FiniVAAPIOutput destroying 2 video surfaces
19:37:05.014 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 2
19:37:05.014 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 3
19:37:05.014 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:05.014 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 0
19:37:05.014 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:05.014 T:139742745683712 DEBUG: VideoPlayer::Sync - Audio - pts: 32159000.000000, cache: 297245.714318, totalcache: 550000.011921
19:37:05.014 T:139742745683712 DEBUG: VideoPlayer::Sync - Video - pts: 32031999.000000, cache: 50000.000000, totalcache: 100000.000000
19:37:05.014 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(31861754.285682)
19:37:05.014 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(31861754.285682)
19:37:05.014 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:05.014 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:05.014 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:05.014 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:05.125 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of -29.461356, start adjusting
19:37:05.125 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.461356 below threshold of 30.000000
19:37:06.135 T:139742780368640 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-40212.207955, adjusted:-40212.207955
19:37:06.506 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:06.553 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:06.553 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:06.553 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:08.180 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:08.222 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:08.222 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:08.222 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:09.075 T:139742754076416 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.68, frameduration: 33366.666667
19:37:09.850 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:09.897 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:09.897 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:09.897 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:11.435 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:11.479 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:11.479 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:11.479 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:13.103 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:13.150 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:13.150 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:13.151 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:14.697 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:14.741 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:14.742 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:14.742 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:14.974 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:14.974 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:14.974 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:14.977 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:15.491 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:16.347 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:16.373 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:16.373 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:16.374 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:17.905 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:17.953 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:17.953 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:17.953 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:18.393 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:18.393 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:37:18.393 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:37:18.393 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:37:18.395 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:18.395 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:18.542 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 194.418708, start adjusting
19:37:18.562 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:18.562 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:18.562 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:18.778 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 0.818240 below threshold of 30.000000
19:37:18.897 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:19.074 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:19.568 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:19.574 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:19.577 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:19.595 T:139743136315136 DEBUG: Previous line repeats 2 times.
19:37:19.595 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:19.596 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:20.511 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:20.511 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:37:20.511 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:37:20.511 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:37:20.515 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:20.515 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:20.637 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 446.339871, start adjusting
19:37:21.022 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 18.193089, last average error: 446.339871
19:37:21.122 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:21.142 T:139743427643136 DEBUG: Previous line repeats 2 times.
19:37:21.142 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of -35.271798, start adjusting
19:37:21.142 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.271798 below threshold of 30.000000
19:37:21.145 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:21.147 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:21.147 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:22.697 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:22.748 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:22.749 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:22.749 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:23.207 T:139743923149184 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left
19:37:24.284 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:24.310 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:24.310 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:24.311 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:24.608 T:139742754076416 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
19:37:24.776 T:139741685065472 DEBUG: Thread JobWorker 139741685065472 terminating (autodelete)
19:37:24.776 T:139742247999232 DEBUG: Thread JobWorker 139742247999232 terminating (autodelete)
19:37:24.776 T:139743438214912 DEBUG: Thread JobWorker 139743438214912 terminating (autodelete)
19:37:24.803 T:139742798628608 DEBUG: Thread JobWorker 139742798628608 terminating (autodelete)
19:37:25.837 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:25.884 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:25.884 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:25.884 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:27.392 T:139743923149184 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left
19:37:27.417 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:27.441 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:27.441 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:27.442 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:28.808 T:139743923149184 DEBUG: OnKey: yellow (0xe5) pressed, action is PlayerProcessInfo
19:37:28.961 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:28.984 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:28.984 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:28.984 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:29.041 T:139743923149184 DEBUG: ------ Window Deinit (DialogPlayerProcessInfo.xml) ------
19:37:29.124 T:139743923149184 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:37:29.124 T:139743923149184 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
19:37:29.990 T:139743923149184 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is StepBack
19:37:29.990 T:139743923149184 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:37:29.990 T:139743923149184 DEBUG: Window DialogSeekBar.xml was already loaded
19:37:29.990 T:139743923149184 DEBUG: Alloc resources: 0.07ms
19:37:29.991 T:139743923149184 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
19:37:29.991 T:139743923149184 DEBUG: Window Custom_1109_TopBarOverlay.xml was already loaded
19:37:29.991 T:139743923149184 DEBUG: Alloc resources: 0.00ms
19:37:30.506 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:30.528 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:30.528 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:30.529 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:30.741 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
19:37:30.741 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
19:37:30.745 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 2
19:37:30.745 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:30.745 T:139742745683712 DEBUG: demuxer seek to: 42184.000000
19:37:30.745 T:139742745683712 DEBUG: SeekTime - seek ended up on time 42042
19:37:30.745 T:139742745683712 DEBUG: demuxer seek to: 42184.000000, success
19:37:30.745 T:139742745683712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
19:37:30.755 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:30.814 T:139742780368640 DEBUG: CDVDAudio::Flush - flush audio stream
19:37:30.814 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:30.814 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:30.814 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:30.815 T:139742754076416 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
19:37:30.815 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:30.815 T:139742754076416 DEBUG: VAAPI::Release pre-cleanup
19:37:30.818 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
19:37:30.818 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1920x1080)
19:37:30.818 T:139742754076416 NOTICE: VDPAU::Open: required extension GL_NV_vdpau_interop not found
19:37:30.818 T:139742754076416 NOTICE: (VDPAU) Close
19:37:30.818 T:139742754076416 DEBUG: VAAPI - open decoder
19:37:30.818 T:139742798628608 DEBUG: Thread Vaapi-Output start, auto delete: false
19:37:30.818 T:139742798628608 NOTICE: COutput::OnStartup: Output Thread created
19:37:30.825 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 1
19:37:30.828 T:139742798628608 DEBUG: VAAPI::COutput::CreateEGLContext - created context
19:37:30.836 T:139742798628608 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
19:37:30.837 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
19:37:30.839 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:30.841 T:139742754076416 WARNING: CVideoSurfaces::ClearReference - surface invalid
19:37:30.841 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:30.858 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:37:30.858 T:139743923149184 NOTICE: VAAPI::Close
19:37:30.858 T:139740975761152 NOTICE: COutput::OnExit: Output Thread terminated
19:37:30.858 T:139740975761152 DEBUG: Thread Vaapi-Output 139740975761152 terminating
19:37:30.858 T:139743923149184 DEBUG: VAAPI::FiniVAAPIOutput destroying 2 video surfaces
19:37:30.860 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 2
19:37:30.860 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 3
19:37:30.860 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:30.860 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 0
19:37:30.860 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:30.860 T:139742745683712 DEBUG: VideoPlayer::Sync - Audio - pts: 42306000.000000, cache: 278639.465570, totalcache: 550000.011921
19:37:30.860 T:139742745683712 DEBUG: VideoPlayer::Sync - Video - pts: 42042000.000000, cache: 50000.000000, totalcache: 100000.000000
19:37:30.860 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(41942000.000000)
19:37:30.860 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:30.860 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(41942000.000000)
19:37:30.860 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:30.860 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:30.860 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:30.962 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 44.547752, start adjusting
19:37:30.962 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 0.547752 below threshold of 30.000000
19:37:32.002 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -173.006660 above threshold of 100.000000
19:37:32.002 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -23.006660 below threshold of 30.000000
19:37:32.152 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:32.197 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:32.197 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:32.197 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:33.059 T:139742780368640 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-26973.811836, adjusted:-26973.811836
19:37:33.640 T:139743923149184 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:37:33.640 T:139743923149184 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
19:37:33.798 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:33.807 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:37:33.807 T:139743923149184 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is StepBack
19:37:33.807 T:139743923149184 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:37:33.807 T:139743923149184 DEBUG: Window DialogSeekBar.xml was already loaded
19:37:33.807 T:139743923149184 DEBUG: Alloc resources: 0.06ms
19:37:33.808 T:139743923149184 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
19:37:33.808 T:139743923149184 DEBUG: Window Custom_1109_TopBarOverlay.xml was already loaded
19:37:33.808 T:139743923149184 DEBUG: Alloc resources: 0.00ms
19:37:33.812 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:33.822 T:139743136315136 DEBUG: Previous line repeats 1 times.
19:37:33.822 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:33.822 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:34.557 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
19:37:34.557 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
19:37:34.561 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 2
19:37:34.561 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:34.561 T:139742745683712 DEBUG: demuxer seek to: 35616.000000
19:37:34.561 T:139742745683712 DEBUG: SeekTime - seek ended up on time 34034
19:37:34.562 T:139742745683712 DEBUG: demuxer seek to: 35616.000000, success
19:37:34.562 T:139742745683712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
19:37:34.601 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:34.630 T:139742780368640 DEBUG: CDVDAudio::Flush - flush audio stream
19:37:34.630 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:34.631 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:34.631 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:34.632 T:139742754076416 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
19:37:34.632 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.632 T:139742754076416 DEBUG: VAAPI::Release pre-cleanup
19:37:34.634 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
19:37:34.634 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1920x1080)
19:37:34.634 T:139742754076416 NOTICE: VDPAU::Open: required extension GL_NV_vdpau_interop not found
19:37:34.634 T:139742754076416 NOTICE: (VDPAU) Close
19:37:34.634 T:139742754076416 DEBUG: VAAPI - open decoder
19:37:34.634 T:139740975761152 DEBUG: Thread Vaapi-Output start, auto delete: false
19:37:34.634 T:139740975761152 NOTICE: COutput::OnStartup: Output Thread created
19:37:34.642 T:139740975761152 DEBUG: VAAPI::COutput::CreateEGLContext - created context
19:37:34.648 T:139740975761152 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
19:37:34.648 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
19:37:34.649 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.651 T:139742754076416 WARNING: CVideoSurfaces::ClearReference - surface invalid
19:37:34.651 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.658 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:37:34.658 T:139743923149184 NOTICE: VAAPI::Close
19:37:34.659 T:139742798628608 NOTICE: COutput::OnExit: Output Thread terminated
19:37:34.659 T:139742798628608 DEBUG: Thread Vaapi-Output 139742798628608 terminating
19:37:34.659 T:139743923149184 DEBUG: VAAPI::FiniVAAPIOutput destroying 2 video surfaces
19:37:34.659 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 2
19:37:34.693 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 1
19:37:34.693 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 3
19:37:34.693 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:34.693 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 0
19:37:34.693 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:34.693 T:139742745683712 DEBUG: VideoPlayer::Sync - Audio - pts: 34295000.000000, cache: 278639.465570, totalcache: 550000.011921
19:37:34.693 T:139742745683712 DEBUG: VideoPlayer::Sync - Video - pts: 34034000.000000, cache: 50000.000000, totalcache: 100000.000000
19:37:34.693 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(33934000.000000)
19:37:34.693 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(33934000.000000)
19:37:34.693 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:34.693 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.693 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:34.693 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.693 T:139742754076416 DEBUG: Previous line repeats 1 times.
19:37:34.693 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 4, Bufferlevel: 0, dropped: 1
19:37:34.693 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.693 T:139742754076416 DEBUG: Previous line repeats 3 times.
19:37:34.694 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 4, Bufferlevel: 0, dropped: 1
19:37:34.694 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.694 T:139742754076416 DEBUG: Previous line repeats 3 times.
19:37:34.694 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 4, Bufferlevel: 0, dropped: 1
19:37:34.694 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:34.695 T:139742754076416 DEBUG: Previous line repeats 3 times.
19:37:34.695 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 4, Bufferlevel: 0, dropped: 1
19:37:34.808 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 38.166953, start adjusting
19:37:34.808 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 0.166953 below threshold of 30.000000
19:37:35.448 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:35.496 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:35.496 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:35.497 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:35.846 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -182.154021 above threshold of 100.000000
19:37:35.847 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.154021 below threshold of 30.000000
19:37:37.124 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:37.168 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:37.168 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:37.168 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:37.457 T:139743923149184 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:37:37.457 T:139743923149184 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
19:37:38.804 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:38.844 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:38.844 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:38.844 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:39.174 T:139742754076416 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
19:37:40.464 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:40.510 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:40.510 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:40.511 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:41.342 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:41.342 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:41.342 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:41.357 T:139743923149184 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:37:41.357 T:139743923149184 DEBUG: Window DialogSeekBar.xml was already loaded
19:37:41.357 T:139743923149184 DEBUG: Alloc resources: 0.06ms
19:37:41.357 T:139743923149184 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
19:37:41.357 T:139743923149184 DEBUG: Window Custom_1109_TopBarOverlay.xml was already loaded
19:37:41.358 T:139743923149184 DEBUG: Alloc resources: 0.00ms
19:37:41.363 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:41.857 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:42.048 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:42.078 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:42.078 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:42.078 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:42.357 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:42.435 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:42.435 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:37:42.435 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:37:42.435 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:37:42.437 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:42.437 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:42.551 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 216.931012, start adjusting
19:37:42.621 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:42.621 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:42.621 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:42.759 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 15.198653, last average error: 216.931012
19:37:42.908 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 158.597625, start adjusting
19:37:42.996 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 8.597625 below threshold of 30.000000
19:37:43.085 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:43.140 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:43.653 T:139743136315136 WARNING: Previous line repeats 1 times.
19:37:43.653 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:43.653 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:37:43.653 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:37:43.653 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:37:43.659 T:139743438214912 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:43.660 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:43.660 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:43.667 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:43.682 T:139743136315136 DEBUG: Previous line repeats 2 times.
19:37:43.682 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:43.682 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:43.763 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:43.763 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:43.763 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:43.796 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 457.841063, start adjusting
19:37:44.273 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:45.208 T:139743136315136 WARNING: Previous line repeats 1 times.
19:37:45.208 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:45.251 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:45.251 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:45.251 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:45.595 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:45.595 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:37:45.595 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:37:45.595 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:37:45.671 T:139742780368640 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
19:37:45.671 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:45.671 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:45.687 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:45.806 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 639.856999, start adjusting
19:37:46.253 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 0.992877, last average error: 639.856999
19:37:46.401 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of -48.482624, start adjusting
19:37:46.401 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.482624 below threshold of 30.000000
19:37:46.780 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:46.809 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:46.809 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:46.809 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:46.946 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:46.946 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:46.946 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:46.993 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:47.139 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:47.139 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:37:47.139 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:37:47.139 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:37:47.143 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:47.143 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:47.290 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 247.130772, start adjusting
19:37:47.498 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 17.264475, last average error: 247.130772
19:37:47.646 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of -30.816025, start adjusting
19:37:47.646 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.816025 below threshold of 30.000000
19:37:48.334 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:48.360 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:48.360 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:48.360 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:49.157 T:139743923149184 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is StepBack
19:37:49.886 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:49.907 T:139743446607616 DEBUG: Previous line repeats 2 times.
19:37:49.907 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
19:37:49.907 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
19:37:49.909 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 2
19:37:49.909 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:49.909 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:49.909 T:139742745683712 DEBUG: demuxer seek to: 35011.000000
19:37:49.909 T:139742745683712 DEBUG: SeekTime - seek ended up on time 34034
19:37:49.909 T:139742745683712 DEBUG: demuxer seek to: 35011.000000, success
19:37:49.909 T:139742745683712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
19:37:49.911 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:49.912 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:49.919 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:49.948 T:139742780368640 DEBUG: CDVDAudio::Flush - flush audio stream
19:37:49.948 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:49.948 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:49.949 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:37:49.949 T:139742754076416 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
19:37:49.949 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:49.950 T:139742754076416 DEBUG: VAAPI::Release pre-cleanup
19:37:49.952 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
19:37:49.952 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1920x1080)
19:37:49.952 T:139742754076416 NOTICE: VDPAU::Open: required extension GL_NV_vdpau_interop not found
19:37:49.952 T:139742754076416 NOTICE: (VDPAU) Close
19:37:49.952 T:139742754076416 DEBUG: VAAPI - open decoder
19:37:49.952 T:139742247999232 DEBUG: Thread Vaapi-Output start, auto delete: false
19:37:49.952 T:139742247999232 NOTICE: COutput::OnStartup: Output Thread created
19:37:49.959 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 1
19:37:49.960 T:139742247999232 DEBUG: VAAPI::COutput::CreateEGLContext - created context
19:37:49.970 T:139742247999232 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
19:37:49.970 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
19:37:49.971 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:49.973 T:139742754076416 WARNING: CVideoSurfaces::ClearReference - surface invalid
19:37:49.973 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:49.990 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:37:49.990 T:139743923149184 NOTICE: VAAPI::Close
19:37:49.991 T:139740975761152 NOTICE: COutput::OnExit: Output Thread terminated
19:37:49.991 T:139740975761152 DEBUG: Thread Vaapi-Output 139740975761152 terminating
19:37:49.991 T:139743923149184 DEBUG: VAAPI::FiniVAAPIOutput destroying 2 video surfaces
19:37:49.993 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 2
19:37:49.993 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 3
19:37:49.993 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:49.993 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 0
19:37:49.993 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:37:49.993 T:139742745683712 DEBUG: VideoPlayer::Sync - Audio - pts: 34295000.000000, cache: 278639.465570, totalcache: 550000.011921
19:37:49.993 T:139742745683712 DEBUG: VideoPlayer::Sync - Video - pts: 34034000.000000, cache: 50000.000000, totalcache: 100000.000000
19:37:49.993 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(33934000.000000)
19:37:49.993 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:49.993 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(33934000.000000)
19:37:49.993 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:49.993 T:139742754076416 DEBUG: Previous line repeats 2 times.
19:37:49.993 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 3, Bufferlevel: 0, dropped: 1
19:37:49.993 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:37:49.994 T:139742754076416 DEBUG: Previous line repeats 2 times.
19:37:49.994 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 3, Bufferlevel: 2, dropped: 1
19:37:50.007 T:139743427643136 DEBUG: Previous line repeats 1 times.
19:37:50.007 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:50.113 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 26.957570, start adjusting
19:37:50.113 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 0.957570 below threshold of 30.000000
19:37:51.152 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -188.612358 above threshold of 100.000000
19:37:51.153 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.612358 below threshold of 30.000000
19:37:51.536 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:51.577 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:51.577 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:51.577 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:52.773 T:139743923149184 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:37:52.774 T:139743923149184 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
19:37:53.196 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:53.238 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:53.238 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:53.238 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:54.341 T:139742754076416 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
19:37:54.867 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:54.915 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:54.915 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:54.915 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:56.346 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:56.346 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:56.346 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:56.357 T:139743923149184 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:37:56.357 T:139743923149184 DEBUG: Window DialogSeekBar.xml was already loaded
19:37:56.357 T:139743923149184 DEBUG: Alloc resources: 0.06ms
19:37:56.357 T:139743923149184 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
19:37:56.357 T:139743923149184 DEBUG: Window Custom_1109_TopBarOverlay.xml was already loaded
19:37:56.357 T:139743923149184 DEBUG: Alloc resources: 0.00ms
19:37:56.399 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:56.446 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:56.469 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:56.469 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:56.469 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:56.856 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:37:58.087 T:139743136315136 WARNING: Previous line repeats 1 times.
19:37:58.087 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:58.131 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:58.131 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:58.132 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:37:58.820 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:58.820 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:37:58.820 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:37:58.820 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:37:58.824 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:37:58.824 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:37:58.953 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 248.361843, start adjusting
19:37:59.134 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:59.135 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:37:59.135 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:37:59.147 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 0.867384, last average error: 248.361843
19:37:59.206 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:37:59.665 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:37:59.691 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:37:59.691 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:37:59.691 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:00.172 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:00.172 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:38:00.172 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:38:00.172 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:38:00.178 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:38:00.178 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:38:00.303 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 286.184665, start adjusting
19:38:00.352 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:00.353 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:38:00.353 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:38:00.873 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:38:01.294 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:01.324 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:01.324 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:01.325 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:02.190 T:139742780368640 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
19:38:02.190 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:38:02.950 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:02.992 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:02.992 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:02.992 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:04.526 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:04.551 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:04.551 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:04.551 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:04.576 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:04.576 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:38:04.576 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:38:04.577 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:38:04.578 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:38:04.578 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:38:04.705 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of 350.632317, start adjusting
19:38:05.002 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error 22.580305, last average error: 350.632317
19:38:05.148 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of -23.889241, start adjusting
19:38:05.148 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.889241 below threshold of 30.000000
19:38:05.404 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:05.404 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:38:05.404 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:38:05.448 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:38:05.923 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:38:06.077 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:06.103 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:06.104 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:06.104 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:06.423 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:38:06.923 T:139743923149184 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is StepBack
19:38:07.625 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:07.649 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:07.649 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:07.649 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:07.673 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
19:38:07.673 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
19:38:07.682 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 2
19:38:07.682 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:38:07.682 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:38:07.682 T:139742745683712 DEBUG: demuxer seek to: 31626.000000
19:38:07.682 T:139742745683712 DEBUG: SeekTime - seek ended up on time 30030
19:38:07.683 T:139742745683712 DEBUG: demuxer seek to: 31626.000000, success
19:38:07.683 T:139742745683712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
19:38:07.736 T:139742780368640 DEBUG: CDVDAudio::Flush - flush audio stream
19:38:07.736 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:38:07.737 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:38:07.737 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:38:07.794 T:139742754076416 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
19:38:07.794 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:07.794 T:139742754076416 DEBUG: VAAPI::Release pre-cleanup
19:38:07.798 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 1
19:38:07.800 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
19:38:07.800 T:139742754076416 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(1920x1080)
19:38:07.800 T:139742754076416 NOTICE: VDPAU::Open: required extension GL_NV_vdpau_interop not found
19:38:07.800 T:139742754076416 NOTICE: (VDPAU) Close
19:38:07.800 T:139742754076416 DEBUG: VAAPI - open decoder
19:38:07.800 T:139740975761152 DEBUG: Thread Vaapi-Output start, auto delete: false
19:38:07.800 T:139740975761152 NOTICE: COutput::OnStartup: Output Thread created
19:38:07.809 T:139740975761152 DEBUG: VAAPI::COutput::CreateEGLContext - created context
19:38:07.816 T:139740975761152 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
19:38:07.816 T:139742754076416 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
19:38:07.817 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:07.819 T:139742754076416 WARNING: CVideoSurfaces::ClearReference - surface invalid
19:38:07.819 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:07.824 T:139743923149184 DEBUG: Previous line repeats 1 times.
19:38:07.824 T:139743923149184 NOTICE: VAAPI::Close
19:38:07.824 T:139742247999232 NOTICE: COutput::OnExit: Output Thread terminated
19:38:07.824 T:139742247999232 DEBUG: Thread Vaapi-Output 139742247999232 terminating
19:38:07.825 T:139743923149184 DEBUG: VAAPI::FiniVAAPIOutput destroying 2 video surfaces
19:38:07.846 T:139742745683712 DEBUG: CVideoPlayer::HandleMessages - player started 2
19:38:07.846 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 3
19:38:07.846 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:38:07.846 T:139742745683712 DEBUG: CVideoPlayer::SetCaching - caching state 0
19:38:07.846 T:139742745683712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
19:38:07.846 T:139742745683712 DEBUG: VideoPlayer::Sync - Audio - pts: 30301000.000000, cache: 278639.465570, totalcache: 550000.011921
19:38:07.846 T:139742745683712 DEBUG: VideoPlayer::Sync - Video - pts: 30030000.000000, cache: 50000.000000, totalcache: 100000.000000
19:38:07.846 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:38:07.846 T:139742754076416 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(30030000.000000)
19:38:07.846 T:139742780368640 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(30030000.000000)
19:38:08.732 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:08.732 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:38:08.732 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:38:08.732 T:139743446607616 DEBUG: UPnP: Building didl for object '/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv'
19:38:08.735 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:08.735 T:139742780368640 DEBUG: CDVDAudio::Resume - resume audio stream
19:38:08.735 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:08.735 T:139743427643136 DEBUG: ActiveAE - start sync of audio stream
19:38:08.735 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:08.735 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 0, dropped: 1
19:38:08.735 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:08.736 T:139742754076416 DEBUG: Previous line repeats 3 times.
19:38:08.736 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 0, dropped: 1
19:38:08.736 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
19:38:08.742 T:139742754076416 DEBUG: Previous line repeats 2 times.
19:38:08.742 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
19:38:08.742 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 1, dropped: 1
19:38:08.744 T:139742754076416 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
19:38:08.861 T:139743427643136 DEBUG: Previous line repeats 2 times.
19:38:08.861 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error of -186.913955, start adjusting
19:38:08.861 T:139743427643136 DEBUG: ActiveAE::SyncStream - average error -0.913955 below threshold of 30.000000
19:38:09.175 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:09.202 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:09.202 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:09.202 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:09.889 T:139742780368640 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-38290.851734, adjusted:-38290.851734
19:38:10.830 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:10.876 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:10.876 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:10.877 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:11.556 T:139743923149184 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:38:11.556 T:139743923149184 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
19:38:12.422 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:12.468 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:12.468 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:12.468 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:13.024 T:139742754076416 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
19:38:14.010 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:14.056 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:14.056 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:14.056 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:15.687 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:15.735 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:15.735 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:15.735 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:17.367 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:17.411 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:17.411 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:17.411 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:19.042 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:19.088 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:19.088 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:19.088 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:19.407 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:19.407 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
19:38:19.408 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
19:38:19.423 T:139743923149184 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:38:19.423 T:139743923149184 DEBUG: Window DialogSeekBar.xml was already loaded
19:38:19.423 T:139743923149184 DEBUG: Alloc resources: 0.11ms
19:38:19.423 T:139743923149184 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
19:38:19.423 T:139743923149184 DEBUG: Window Custom_1109_TopBarOverlay.xml was already loaded
19:38:19.424 T:139743923149184 DEBUG: Alloc resources: 0.00ms
19:38:19.427 T:139742780368640 DEBUG: CDVDAudio::Pause - pausing audio stream
19:38:19.889 T:139742754076416 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:38:20.700 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:20.727 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:20.727 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:20.727 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:22.346 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:22.393 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:22.393 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:22.393 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:24.024 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:24.070 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:24.070 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:24.070 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:25.702 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:25.746 T:139743136315136 DEBUG: Previous line repeats 3 times.
19:38:25.746 T:139743136315136 DEBUG: GetMovieId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idMovie from movie where idFile=2021
19:38:25.746 T:139743136315136 DEBUG: GetEpisodeId (/data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv), query = select idEpisode from episode where idFile=2021
19:38:26.574 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:26.589 T:139743923149184 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:38:26.589 T:139743923149184 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:38:26.591 T:139743923149184 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:38:26.593 T:139743923149184 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:38:26.594 T:139743923149184 DEBUG: Window MyVideoNav.xml was already loaded
19:38:26.594 T:139743923149184 DEBUG: Alloc resources: 0.71ms
19:38:26.594 T:139743923149184 DEBUG: CGUIMediaWindow::GetDirectory (videodb://tvshows/titles/8/8/?tvshowid=8)
19:38:26.594 T:139743923149184 DEBUG: ParentPath = [videodb://tvshows/titles/8/8/?tvshowid=8]
19:38:26.597 T:139743923149184 DEBUG: RunQuery took 1 ms for 17 items query: select * from episode_view WHERE (episode_view.idShow = 8) AND ((episode_view.c12 = 8 or (episode_view.c12 = 0 and (episode_view.c15 = 0 or episode_view.c15 = 8))))
19:38:26.599 T:139743923149184 DEBUG: WindowVideoNav::GetDirectory
19:38:26.615 T:139742247999232 DEBUG: Thread BackgroundLoader start, auto delete: false
19:38:26.621 T:139743923149184 NOTICE: CVideoPlayer::CloseFile()
19:38:26.621 T:139743923149184 NOTICE: VideoPlayer: waiting for threads to exit
19:38:26.624 T:139742247999232 DEBUG: Thread BackgroundLoader 139742247999232 terminating
19:38:26.630 T:139742745683712 NOTICE: CVideoPlayer::OnExit()
19:38:26.630 T:139742745683712 NOTICE: Closing stream player 1
19:38:26.630 T:139742745683712 NOTICE: Waiting for audio thread to exit
19:38:26.630 T:139742780368640 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
19:38:26.630 T:139742780368640 NOTICE: thread end: CVideoPlayerAudio::OnExit()
19:38:26.630 T:139742745683712 NOTICE: Closing audio device
19:38:26.630 T:139742780368640 DEBUG: Thread VideoPlayerAudio 139742780368640 terminating
19:38:26.633 T:139742745683712 DEBUG: CDVDAudio::Flush - flush audio stream
19:38:26.633 T:139743427643136 DEBUG: CActiveAE::DiscardStream - audio stream deleted
19:38:26.633 T:139743427643136 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:38:26.634 T:139742745683712 DEBUG: Previous line repeats 3 times.
19:38:26.634 T:139742745683712 NOTICE: Deleting audio codec
19:38:26.634 T:139742745683712 NOTICE: Closing stream player 2
19:38:26.634 T:139742745683712 NOTICE: waiting for video thread to exit
19:38:26.634 T:139742754076416 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
19:38:26.634 T:139742754076416 NOTICE: thread end: video_thread
19:38:26.634 T:139742754076416 DEBUG: Thread VideoPlayerVideo 139742754076416 terminating
19:38:26.634 T:139742745683712 NOTICE: deleting video codec
19:38:26.635 T:139742745683712 DEBUG: VAAPI::Release pre-cleanup
19:38:26.637 T:139742745683712 NOTICE: Closing stream player 3
19:38:26.638 T:139742745683712 DEBUG: OnPlayBackStopped: play state was 2, starting 0
19:38:26.638 T:139742745683712 DEBUG: Thread VideoPlayer 139742745683712 terminating
19:38:26.638 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
19:38:26.638 T:139743923149184 NOTICE: VideoPlayer: finished waiting
19:38:26.638 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
19:38:26.639 T:139743923149184 DEBUG: DeleteRenderer - deleting renderer
19:38:26.639 T:139743923149184 NOTICE: VAAPI::Close
19:38:26.639 T:139740975761152 NOTICE: COutput::OnExit: Output Thread terminated
19:38:26.639 T:139740975761152 DEBUG: Thread Vaapi-Output 139740975761152 terminating
19:38:26.640 T:139743923149184 DEBUG: VAAPI::FiniVAAPIOutput destroying 5 video surfaces
19:38:26.642 T:139743923149184 NOTICE: VAAPI::Close - closing decoder context
19:38:26.644 T:139743923149184 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:38:26.719 T:139740975761152 DEBUG: Thread JobWorker start, auto delete: true
19:38:26.734 T:139743923149184 NOTICE: CVideoPlayer::CloseFile()
19:38:26.734 T:139743923149184 NOTICE: VideoPlayer: waiting for threads to exit
19:38:26.734 T:139743923149184 NOTICE: VideoPlayer: finished waiting
19:38:26.734 T:139743923149184 NOTICE: CVideoPlayer::CloseFile()
19:38:26.734 T:139743923149184 NOTICE: VideoPlayer: waiting for threads to exit
19:38:26.734 T:139743923149184 NOTICE: VideoPlayer: finished waiting
19:38:26.735 T:139743923149184 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
19:38:26.737 T:139743923149184 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:38:26.907 T:139743923149184 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
19:38:26.991 T:139740975761152 DEBUG: DoWork - Saving file state for video item /data/TV/The.Walking.Dead/The.Walking.Dead.S08E01.1080p.WEB-DL-tbs.mkv
19:38:27.056 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
19:38:27.056 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
19:38:27.280 T:139743136315136 DEBUG: CWebServer[8080]: request received for /jsonrpc
19:38:35.125 T:139741693458176 DEBUG: Previous line repeats 9 times.
19:38:35.126 T:139741693458176 INFO: JSONRPC Server: Disconnection detected
19:38:38.183 T:139743923149184 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so)
19:38:57.234 T:139740975761152 DEBUG: Thread JobWorker 139740975761152 terminating (autodelete)
19:39:07.977 T:139741701850880 NOTICE: ES: Client from ::ffff:172.16.16.34 timed out
19:40:04.345 T:139743127922432 DEBUG: Failed to connect to LIRC. Giving up.
19:40:04.345 T:139743127922432 DEBUG: Thread RemoteControl 139743127922432 terminating
19:41:26.640 T:139743446607616 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc
19:41:26.640 T:139743446607616 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated
19:41:26.659 T:139743923149184 DEBUG: ------ Window Init () ------
19:41:26.659 T:139743923149184 DEBUG: Alloc resources: 0.00ms (0.00 ms skin load)
20:05:00.769 T:139742259189504 DEBUG: Thread JobWorker start, auto delete: true
20:05:00.769 T:139742259189504 INFO: WEATHER: Downloading weather
20:05:00.769 T:139743127922432 DEBUG: Thread LanguageInvoker start, auto delete: false
20:05:00.769 T:139743127922432 INFO: initializing python engine.
20:05:00.855 T:139743127922432 DEBUG: CPythonInvoker(2, /home/bud/.kodi/addons/weather.yahoo/default.py): start processing
20:05:00.907 T:139743127922432 DEBUG: -->Python Interpreter Initialized<--
20:05:00.907 T:139743127922432 DEBUG: CPythonInvoker(2, /home/bud/.kodi/addons/weather.yahoo/default.py): the source file to load is "/home/bud/.kodi/addons/weather.yahoo/default.py"
20:05:00.907 T:139743127922432 DEBUG: CPythonInvoker(2, /home/bud/.kodi/addons/weather.yahoo/default.py): setting the Python path to /home/bud/.kodi/addons/weather.yahoo:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
20:05:00.907 T:139743127922432 DEBUG: CPythonInvoker(2, /home/bud/.kodi/addons/weather.yahoo/default.py): entering source directory /home/bud/.kodi/addons/weather.yahoo
20:05:00.907 T:139743127922432 DEBUG: CPythonInvoker(2, /home/bud/.kodi/addons/weather.yahoo/default.py): instantiating addon using automatically obtained id of "weather.yahoo" dependent on version 2.25.0 of the xbmc.python api
20:05:01.027 T:139743127922432 DEBUG: weather.yahoo: version 4.3.1 started: ['/home/bud/.kodi/addons/weather.yahoo/default.py', '1']
20:05:01.027 T:139743127922432 DEBUG: weather.yahoo: weather location: 784794
20:05:01.439 T:139743127922432 DEBUG: weather.yahoo: forecast data: {"query":{"count":1,"created":"2018-03-17T19:05:01Z","lang":"en-US","results":{"channel":{"units":{"distance":"km","pressure":"mb","speed":"km/h","temperature":"C"},"title":"Yahoo! Weather - Zurich, ZH, CH","link":"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-784794/","description":"Yahoo! Weather for Zurich, ZH, CH","language":"en-us","lastBuildDate":"Sat, 17 Mar 2018 08:05 PM CET","ttl":"60","location":{"city":"Zurich","country":"Switzerland","region":" ZH"},"wind":{"chill":"36","direction":"60","speed":"17.70"},"atmosphere":{"humidity":"88","pressure":"31730.47","rising":"0","visibility":"23.50"},"astronomy":{"sunrise":"6:34 am","sunset":"6:35 pm"},"image":{"title":"Yahoo! Weather","width":"142","height":"18","link":"http://weather.yahoo.com","url":"http://l.yimg.com/a/i/brand/purplelogo//uh/us/news-wea.gif"},"item":{"title":"Conditions for Zurich, ZH, CH at 07:00 PM CET","lat":"47.38427","long":"8.52959","link":"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-784794/","pubDate":"Sat, 17 Mar 2018 07:00 PM CET","condition":{"code":"11","date":"Sat, 17 Mar 2018 07:00 PM CET","temp":"4","text":"Showers"},"forecast":[{"code":"5","date":"17 Mar 2018","day":"Sat","high":"7","low":"0","text":"Rain And Snow"},{"code":"16","date":"18 Mar 2018","day":"Sun","high":"3","low":"0","text":"Snow"},{"code":"26","date":"19 Mar 2018","day":"Mon","high":"1","low":"-2","text":"Cloudy"},{"code":"30","date":"20 Mar 2018","day":"Tue","high":"3","low":"-4","text":"Partly Cloudy"},{"code":"30","date":"21 Mar 2018","day":"Wed","high":"2","low":"-6","text":"Partly Cloudy"},{"code":"30","date":"22 Mar 2018","day":"Thu","high":"3","low":"-3","text":"Partly Cloudy"},{"code":"30","date":"23 Mar 2018","day":"Fri","high":"5","low":"-2","text":"Partly Cloudy"},{"code":"28","date":"24 Mar 2018","day":"Sat","high":"7","low":"0","text":"Mostly Cloudy"},{"code":"28","date":"25 Mar 2018","day":"Sun","high":"8","low":"0","text":"Mostly Cloudy"},{"code":"39","date":"26 Mar 2018","day":"Mon","high":"10","low":"2","text":"Scattered Showers"}],"description":"<![CDATA[<img src=\"http://l.yimg.com/a/i/us/we/52/11.gif\"/>\n<BR />\n<b>Current Conditions:</b>\n<BR />Showers\n<BR />\n<BR />\n<b>Forecast:</b>\n<BR /> Sat - Rain And Snow. High: 7Low: 0\n<BR /> Sun - Snow. High: 3Low: 0\n<BR /> Mon - Cloudy. High: 1Low: -2\n<BR /> Tue - Partly Cloudy. High: 3Low: -4\n<BR /> Wed - Partly Cloudy. High: 2Low: -6\n<BR />\n<BR />\n<a href=\"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-784794/\">Full Forecast at Yahoo! Weather</a>\n<BR />\n<BR />\n<BR />\n]]>","guid":{"isPermaLink":"false"}}}}}}
20:05:01.443 T:139743127922432 DEBUG: weather.yahoo: available locations: 1
20:05:01.443 T:139743127922432 DEBUG: weather.yahoo: finished
20:05:01.443 T:139743127922432 INFO: CPythonInvoker(2, /home/bud/.kodi/addons/weather.yahoo/default.py): script successfully run
20:05:01.452 T:139743127922432 INFO: Python script stopped
20:05:01.452 T:139743127922432 DEBUG: Thread LanguageInvoker 139743127922432 terminating
20:05:01.479 T:139742259189504 DEBUG: POParser: loaded 130 weather tokens
20:05:11.480 T:139743923149184 INFO: Python, unloading python shared library because no scripts are running anymore
20:05:11.486 T:139743923149184 DEBUG: UnloadExtensionLibs, clearing python extension libraries
20:05:31.479 T:139742259189504 DEBUG: Thread JobWorker 139742259189504 terminating (autodelete)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment