- Servus!
mein Raspberry 3 kann die Videos nicht ruckelfrei abspielen.
- Libreelec 8.1.1
- Codecs gekauft und eingegeben (unter /flash/config.txt)
- die Befehle
vcgencmd codec_enabled MPG2
vcgencmd codec_enabled WVC1
liefern mir jeweils "enabled"
- Netzwerk nur über Netzwerkkabel
- habe testweise openelec installiert: dasselbe Ergebnis
- die Prozessorauslastung ist meist bei 90%.Gegencheck mit meinem alten Raspberry 1:- dasselbe Video- dasselbe Netzwerkkabelfunktioniert beim rpi1Bin ratlos!Was könnte das sein?Grüße!Angelo
Video Hardware Beschleunigung geht nicht
-
angelosarikis -
10. September 2017 um 14:52 -
Erledigt
Heute ist Stammtischzeit:
Jeden Donnerstag 20:30 Uhr hier im Chat.
Wer Lust hat, kann sich gerne beteiligen. ;)
Jeden Donnerstag 20:30 Uhr hier im Chat.
Wer Lust hat, kann sich gerne beteiligen. ;)
-
-
Video Hardware Beschleunigung geht nicht? Schau mal ob du hier fündig wirst!
-
- Offizieller Beitrag
Und welche Filme willst du dir anschauen bzw. Welche Codecs wurden da verwendet? Ich hab in meiner riesen Sammlung keinen einzigen Film der diese Codecs brauch. Lass doch mal Mediainfo über dein Video laufen und poste den output
-
Wie greifst Du auf die Videodaten (Filme) zu?
- lokale Festplatte/USB-Stick am RPi?
- Über LAN oder WLAN auf ein NAS (oder Ähnliches)?
- Über das Internet?
Insbesondere bei letzterem kann das Problem auch die Netzwerkgeschwindigkeit sein...Beim WLAN ist die Frage der Signalstärke entscheidendEDIT: Wer lesen kann ist im Vorteil! -
Ich habe es soeben wieder probiert: der Raspi 1 schafft das gleiche Video (1080p), der Raspi 3 nicht.
Zu Beginn des Videos tut sich der Raspi3 sofort schwer. Es kommt eine Uhr und er ladet doch 5-7 sec. Der Raspi1 startet sofort mit dem Video. Ich habe den Raspi 3 auch Wireless probiert. Da startet es am Anfang noch langsamer.
Es könnte vielleicht ein Netzwerkproblem sein. Innerhalb Raspi 3. Dass es an der Netzwerk-Infrastruktur liegt, kann ich ausschließen. Ich habe dasselbe Kabel vom Raspi 1 beim Raspi3 probiert, mit dem gleichen Ergebnis, dass es beim rpi1 geht und beim rpi3 nicht.
Hat der rpi3 / libreelec vielleicht eine Netzwerk Handbremse gezogen?
Hier jedenfalls mal die Infos zum Video:
Format : Matroska
Format-Version : Version 4 / Version 2
Dateigröße : 3,15 GiB
Dauer : 53 min 29s
Gesamte Bitrate : 8 424 kb/s
Kodierungs-Datum : UTC 2013-03-30 16:33:20
Kodierendes Programm : mkvmerge v6.1.0 ('Old Devil') built on Mar 2 2013 14:32:37
verwendete Encoder-Bibliothek : libebml v1.3.0 + libmatroska v1.4.0Video
ID : 1
Format : AVC
Format/Info : Advanced Video Codec
Format-Profil : High@L4.1
Format-Einstellungen für CABAC : Ja
Format-Einstellungen für RefFrames : 4 frames
Codec-ID : V_MPEG4/ISO/AVC
Dauer : 53 min 29s
Bitrate : 5 980 kb/s
Breite : 1 920 Pixel
Höhe : 1 080 Pixel
Bildseitenverhältnis : 16:9
Modus der Bildwiederholungsrate : konstant
Bildwiederholungsrate : 23,976 (24000/1001) FPS
ColorSpace : YUV
ChromaSubsampling/String : 4:2:0
BitDepth/String : 8 bits
Scantyp : progressiv
Bits/(Pixel*Frame) : 0.120
Stream-Größe : 2,23 GiB (71%)
verwendete Encoder-Bibliothek : x264 core 130 r2273 b3065e6
Kodierungseinstellungen : cabac=1 / ref=4 / deblock=1:-3:-3 / analyse=0x3:0x133 / me=umh / subme=7 / psy=1 / psy_rd=1.00:0.00 / mixed_ref=1 / me_range=16 / chroma_me=1 / trellis=1 / 8x8dct=1 / cqm=0 / deadzone=21,11 / fast_pskip=0 / chroma_qp_offset=-1 / threads=12 / lookahead_threads=2 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / bluray_compat=0 / constrained_intra=0 / bframes=3 / b_pyramid=2 / b_adapt=1 / b_bias=0 / direct=3 / weightb=1 / open_gop=0 / weightp=2 / keyint=240 / keyint_min=23 / scenecut=40 / intra_refresh=0 / rc_lookahead=40 / rc=crf / mbtree=1 / crf=20.0 / qcomp=0.60 / qpmin=10 / qpmax=51 / qpstep=4 / vbv_maxrate=62500 / vbv_bufsize=78125 / crf_max=0.0 / nal_hrd=none / ip_ratio=1.40 / aq=1:1.00
Default : Ja
Forced : NeinAudio #1
ID : 2
Format : DTS
Format/Info : Digital Theater Systems
Format_Settings_Mode : 16
Format-Einstellungen für Endianess : Big
Codec-ID : A_DTS
Dauer : 53 min 29s
Bitraten-Modus : konstant
Bitrate : 768 kb/s
Kanäle : 6 Kanäle
Kanal-Positionen : Front: L C R, Side: L R, LFE
Samplingrate : 48,0 kHz
Bildwiederholungsrate : 93,750 FPS (512 SPF)
BitDepth/String : 24 bits
Stream-Größe : 294 MiB (9%)
Sprache : Deutsch
Default : Ja
Forced : NeinAudio #2
ID : 3
Format : DTS
Format/Info : Digital Theater Systems
Format_Settings_Mode : 16
Format-Einstellungen für Endianess : Big
Codec-ID : A_DTS
Dauer : 53 min 29s
Bitraten-Modus : konstant
Bitrate : 1 509 kb/s
Kanäle : 6 Kanäle
Kanal-Positionen : Front: L C R, Side: L R, LFE
Samplingrate : 48,0 kHz
Bildwiederholungsrate : 93,750 FPS (512 SPF)
BitDepth/String : 24 bits
Stream-Größe : 577 MiB (18%)
Sprache : Englisch
Default : Nein
Forced : NeinText
ID : 4
Format : UTF-8
Codec-ID : S_TEXT/UTF8
Codec-ID/Info : UTF-8 Plain Text
Sprache : Deutsch
Default : Ja
Forced : Nein -
-
liegt am wlan
ich nutze zwar nur OSMC aber auch mein PI2 und PI3 können das von wlan nicht ruckelfrei spielen,
PI2 mit 5m zum Router und wlan Adapter erreicht dabei 271Mbit der PI3 mit internem wlan nur 58 Mbit beide können aber am LAN mit 100 Mbit alles ruckelfrei abspielen.
hat mit Hardwarebeschleunigung IMHO aber nichts zu tun!
-
Hallo!
jar: Ja. Ich glaube mittlerweile auch, dass es ein Netzwerk Thema ist. Ich verwende aber auch beim rpi3 das Kabel. Wie auch der Rpi1. Warum geht der rpi1 schon und der rpi3 nicht? Wenn ich per smb von WIndows auf die Libreelec Freigabe kopiere (auf die Karte), dann geht das am Anfang auch mit fast 10 MByte / sec..
Hm?
-
ZWISCHENSTAND:
- ist wohl nicht die Video-Engine, sondern eher ein Netzwerkproblem, da
1) das Video von der SD Karte problemlos abgespielt wird und
2) das Video von einem Raspberry PI1 auch problemlos gespielt wird;- es ist wohl auch kein Infrastruktur-Netzwerk Problem, da
dasselbe Video vom selben Server über das gleiche Kabel (ich habe es umgesteckt) von einem RPI1 problemlos abgespielt wird. Der RPI3 wird nur über Kabel angeschlossen.Nochmal das Phänomen von meinem RPI3:
- ich beginne, das Video zu schauen (im Libreelec), und sofort kommt der Kreis, wo das Laden angezeigt wird. Das dauert dann ca. 10 sec., danach spielt das Video und stockt alle 2-4 sec.Ich habe Log gemacht. S. unten. (Per Attachement ging bei mir nicht)
Kann mir bitte jemand helfen?
Thx!Code
Alles anzeigen18:18:47.825 T:1945120768 NOTICE: special://profile/ is mapped to: special://masterprofile/ 18:18:47.825 T:1945120768 NOTICE: ----------------------------------------------------------------------- 18:18:47.825 T:1945120768 NOTICE: Starting Kodi (17.4 Git:7fc6da0). Platform: Linux ARM 32-bit 18:18:47.825 T:1945120768 NOTICE: Using Release Kodi x32 build (version for Raspberry Pi) 18:18:47.825 T:1945120768 NOTICE: Kodi compiled Aug 29 2017 by GCC 6.2.0 for Linux ARM 32-bit version 4.9.43 (264491) 18:18:47.825 T:1945120768 NOTICE: Running on LibreELEC (official): 8.1.1 8.2, kernel: Linux ARM 32-bit version 4.9.43 18:18:47.826 T:1945120768 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi 18:18:47.826 T:1945120768 NOTICE: Host CPU: ARMv7 Processor rev 4 (v7l), 4 cores available 18:18:47.826 T:1945120768 NOTICE: ARM Features: Neon enabled 18:18:47.826 T:1945120768 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi/ 18:18:47.826 T:1945120768 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi 18:18:47.826 T:1945120768 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons 18:18:47.826 T:1945120768 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata 18:18:47.826 T:1945120768 NOTICE: special://envhome/ is mapped to: /storage 18:18:47.826 T:1945120768 NOTICE: special://home/ is mapped to: /storage/.kodi 18:18:47.826 T:1945120768 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp 18:18:47.826 T:1945120768 NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp 18:18:47.826 T:1945120768 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin 18:18:47.826 T:1945120768 NOTICE: Local hostname: LibreELECrpi3 18:18:47.826 T:1945120768 NOTICE: Log File is located: /storage/.kodi/temp//kodi.log 18:18:47.826 T:1945120768 NOTICE: ----------------------------------------------------------------------- 18:18:47.850 T:1945120768 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files 18:18:47.857 T:1945120768 NOTICE: load settings... 18:18:47.953 T:1945120768 NOTICE: Found 2 Lists of Devices 18:18:47.953 T:1945120768 NOTICE: Enumerated PI devices: 18:18:47.953 T:1945120768 NOTICE: Device 1 18:18:47.953 T:1945120768 NOTICE: m_deviceName : HDMI 18:18:47.953 T:1945120768 NOTICE: m_displayName : HDMI 18:18:47.953 T:1945120768 NOTICE: m_displayNameExtra: 18:18:47.953 T:1945120768 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 18:18:47.953 T:1945120768 NOTICE: m_channels : FL,FR 18:18:47.953 T:1945120768 NOTICE: m_sampleRates : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000 18:18:47.953 T:1945120768 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP,AE_FMT_RAW 18:18:47.953 T:1945120768 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_EAC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512 18:18:47.953 T:1945120768 NOTICE: Device 2 18:18:47.953 T:1945120768 NOTICE: m_deviceName : Analogue 18:18:47.954 T:1945120768 NOTICE: m_displayName : Analogue 18:18:47.954 T:1945120768 NOTICE: m_displayNameExtra: 18:18:47.954 T:1945120768 NOTICE: m_deviceType : AE_DEVTYPE_PCM 18:18:47.954 T:1945120768 NOTICE: m_channels : FL,FR 18:18:47.954 T:1945120768 NOTICE: m_sampleRates : 48000 18:18:47.954 T:1945120768 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP 18:18:47.954 T:1945120768 NOTICE: m_streamTypes : No passthrough capabilities 18:18:47.954 T:1945120768 NOTICE: Device 3 18:18:47.954 T:1945120768 NOTICE: m_deviceName : Both 18:18:47.954 T:1945120768 NOTICE: m_displayName : HDMI and Analogue 18:18:47.954 T:1945120768 NOTICE: m_displayNameExtra: 18:18:47.954 T:1945120768 NOTICE: m_deviceType : AE_DEVTYPE_PCM 18:18:47.954 T:1945120768 NOTICE: m_channels : FL,FR 18:18:47.954 T:1945120768 NOTICE: m_sampleRates : 48000 18:18:47.954 T:1945120768 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP 18:18:47.954 T:1945120768 NOTICE: m_streamTypes : No passthrough capabilities 18:18:47.954 T:1945120768 NOTICE: Enumerated PULSE devices: 18:18:47.954 T:1945120768 NOTICE: Device 1 18:18:47.954 T:1945120768 NOTICE: m_deviceName : Default 18:18:47.954 T:1945120768 NOTICE: m_displayName : Default 18:18:47.954 T:1945120768 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 18:18:47.954 T:1945120768 NOTICE: m_deviceType : AE_DEVTYPE_PCM 18:18:47.954 T:1945120768 NOTICE: m_channels : FL,FR 18:18:47.954 T:1945120768 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 18:18:47.954 T:1945120768 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 18:18:47.955 T:1945120768 NOTICE: m_streamTypes : No passthrough capabilities 18:18:47.968 T:1945120768 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml 18:18:47.968 T:1945120768 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are... <?xml version="1.0" encoding="UTF-8" ?> <advancedsettings> <showexitbutton>false</showexitbutton> <remotedelay>1</remotedelay> <fanartres>720</fanartres> <imageres>540</imageres> <samba> <clienttimeout>30</clienttimeout> </samba> </advancedsettings> 18:18:47.969 T:1945120768 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 18:18:47.969 T:1945120768 NOTICE: Default Video Player: VideoPlayer 18:18:47.969 T:1945120768 NOTICE: Default Audio Player: paplayer 18:18:47.969 T:1945120768 NOTICE: Disabled debug logging due to GUI setting. Level 0. 18:18:47.970 T:1945120768 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 18:18:47.970 T:1945120768 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 18:18:47.971 T:1945120768 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 18:18:47.971 T:1945120768 NOTICE: Loaded playercorefactory configuration 18:18:47.972 T:1945120768 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 18:18:47.972 T:1945120768 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 18:18:47.997 T:1945120768 NOTICE: Running database version Addons27 18:18:48.070 T:1945120768 NOTICE: ADDONS: Using repository repository.kodi.game 18:18:48.071 T:1945120768 NOTICE: ADDONS: Using repository repository.libreelec.tv 18:18:48.071 T:1945120768 NOTICE: ADDONS: Using repository repository.xbmc.org 18:18:48.133 T:1945120768 NOTICE: Raspberry PI firmware version: Aug 8 2017 12:05:45 Copyright (c) 2012 Broadcom version 196063438d7bb0769861c86f948ae653727adb7e (clean) (release) 18:18:48.133 T:1945120768 NOTICE: ARM mem: 768MB GPU mem: 256MB MPG2:1 WVC1:1 18:18:48.133 T:1945120768 NOTICE: cache.memorysize: 20MB libass.cache: 0MB 18:18:48.133 T:1945120768 NOTICE: Config: arm_freq=1200 audio_pwm_mode=1 config_hdmi_boost=5 core_freq=400 desired_osc_freq=0x36ee80 disable_commandline_tags=2 disable_l2cache=1 force_eeprom_read=1 force_pwm_open=1 framebuffer_ignore_alpha=1 framebuffer_swap=1 gpu_freq=300 hdmi_channel_map=8 hdmi_force_cec_address=65535 hdmi_ignore_cec_init=1 init_uart_clock=0x2dc6c00 lcd_framerate=60 over_voltage_avs=43750 over_voltage_avs_boost=43750 overscan_bottom=48 overscan_left=48 overscan_right=48 overscan_top=48 pause_burst_frames=1 program_serial_random=1 sdram_freq=450 temp_limit=85 18:18:48.134 T:1945120768 NOTICE: Config: decode_MPG2=0x9c1fb1e4 decode_WVC1=0xabdffd57 device_tree=- 18:18:48.134 T:1945120768 NOTICE: InitWindowSystem: Using EGL Implementation: raspberrypi 18:18:48.136 T:1945120768 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 60.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 59.940063 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 60.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 59.940063 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1024 x 768 for display 0 with 1024 x 768 @ 60.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1024 x 768 for display 0 with 1024 x 768 @ 59.940063 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1280 x 1024 for display 0 with 1280 x 1024 @ 60.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1280 x 1024 for display 0 with 1280 x 1024 @ 59.940063 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 59.940063 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 59.940063 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 59.940063 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 720 x 576 for display 0 with 720 x 576 @ 50.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 50.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 24.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 23.976025 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 30.000000 Hz 18:18:48.136 T:1945120768 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 29.970032 Hz 18:18:48.136 T:1945120768 NOTICE: Found (1920x1080@60.000000) at 28, setting to RES_DESKTOP at 16 18:18:48.136 T:1945120768 NOTICE: Checking resolution 16 18:18:48.431 T:1945120768 NOTICE: GL_VENDOR = Broadcom 18:18:48.431 T:1945120768 NOTICE: GL_RENDERER = VideoCore IV HW 18:18:48.432 T:1945120768 NOTICE: GL_VERSION = OpenGL ES 2.0 18:18:48.432 T:1945120768 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00 18:18:48.432 T:1945120768 NOTICE: GL_EXTENSIONS = GL_OES_compressed_ETC1_RGB8_texture GL_OES_compressed_paletted_texture GL_OES_texture_npot GL_OES_depth24 GL_OES_vertex_half_float GL_OES_EGL_image GL_OES_EGL_image_external GL_EXT_discard_framebuffer GL_OES_rgb8_rgba8 GL_OES_depth32 GL_OES_mapbuffer GL_EXT_texture_format_BGRA8888 GL_APPLE_rgb_422 GL_EXT_debug_marker 18:18:49.135 T:1858073504 NOTICE: Running database version Addons27 18:18:49.136 T:1858073504 NOTICE: Running database version ViewModes6 18:18:49.138 T:1858073504 NOTICE: Running database version Textures13 18:18:49.147 T:1858073504 NOTICE: Running database version MyMusic60 18:18:49.157 T:1858073504 NOTICE: Running database version MyVideos107 18:18:49.159 T:1858073504 NOTICE: Running database version TV29 18:18:49.161 T:1858073504 NOTICE: Running database version Epg11 18:18:49.164 T:1945120768 NOTICE: start dvd mediatype detection 18:18:49.275 T:1816130464 NOTICE: Register - new cec device registered on cec->RPI: CEC Adapter (2708:1001) 18:18:49.601 T:1945120768 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 18:18:49.914 T:1945120768 NOTICE: initialize done 18:18:49.914 T:1945120768 NOTICE: Running the application... 18:18:49.953 T:1945120768 NOTICE: starting zeroconf publishing 18:18:49.954 T:1945120768 NOTICE: CWebServer[8080]: Started 18:18:49.955 T:1945120768 NOTICE: starting upnp client 18:18:49.961 T:1668281248 NOTICE: ES: Starting UDP Event server on port 9777 18:18:49.962 T:1668281248 NOTICE: UDP: Listening on port 9777 (ipv6 : false) 18:18:50.277 T:1746924448 WARNING: CSkinInfo: failed to load skin settings 18:20:21.275 T:1945120768 NOTICE: Samba is idle. Closing the remaining connections 18:28:15.921 T:1945120768 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 18:28:15.922 T:1945120768 NOTICE: Enabled debug logging due to GUI setting. Level 2. 18:28:15.923 T:1945120768 DEBUG: ------ Window Init () ------ 18:28:16.070 T:1788867488 DEBUG: PushCecKeypress - received key b duration 149 (rep:0 size:0) 18:28:16.070 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:28:31.333 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 0 (rep:0 size:0) 18:28:31.334 T:1788867488 DEBUG: PushCecKeypress - added key ed 18:28:31.338 T:1945120768 DEBUG: OnKey: 237 (0xed, obc18) pressed, action is PreviousMenu 18:28:31.359 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:28:31.528 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 195 (rep:0 size:0) 18:28:31.528 T:1788867488 DEBUG: PushCecKeypress - ignored key ed 18:28:31.652 T:1945120768 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 18:28:31.655 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:28:31.655 T:1945120768 DEBUG: ------ Window Init (EventLog.xml) ------ 18:28:31.656 T:1945120768 DEBUG: CGUIMediaWindow::GetDirectory (events://) 18:28:31.656 T:1945120768 DEBUG: ParentPath = [events://] 18:28:36.198 T:1788867488 DEBUG: PushCecKeypress - received key d8 duration 0 (rep:0 size:0) 18:28:36.198 T:1788867488 DEBUG: PushCecKeypress - added key d8 18:28:36.239 T:1945120768 DEBUG: OnKey: menu (0xd8) pressed, action is Back 18:28:36.239 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:28:36.326 T:1788867488 DEBUG: PushCecKeypress - received key d8 duration 127 (rep:0 size:0) 18:28:36.326 T:1788867488 DEBUG: PushCecKeypress - ignored key d8 18:28:36.558 T:1945120768 DEBUG: ------ Window Deinit (EventLog.xml) ------ 18:28:36.571 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:28:36.571 T:1945120768 DEBUG: ------ Window Init (Settings.xml) ------ 18:28:42.630 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 0 (rep:0 size:0) 18:28:42.630 T:1788867488 DEBUG: PushCecKeypress - added key ed 18:28:42.648 T:1945120768 DEBUG: OnKey: 237 (0xed, obc18) pressed, action is PreviousMenu 18:28:42.648 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:28:42.798 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 168 (rep:0 size:0) 18:28:42.798 T:1788867488 DEBUG: PushCecKeypress - ignored key ed 18:28:42.966 T:1945120768 DEBUG: ------ Window Deinit (Settings.xml) ------ 18:28:42.988 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:28:42.988 T:1945120768 DEBUG: ------ Window Init (Home.xml) ------ 18:28:44.288 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:44.288 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:44.290 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:44.458 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 170 (rep:0 size:0) 18:28:44.458 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:44.988 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:28:44.988 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:28:45.019 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:28:45.208 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 220 (rep:0 size:0) 18:28:45.208 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:28:45.460 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:28:45.461 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:28:45.475 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:28:45.588 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 127 (rep:0 size:0) 18:28:45.588 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:28:46.253 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:28:46.253 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:28:46.264 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:28:46.402 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 150 (rep:0 size:0) 18:28:46.402 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:28:46.702 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:46.702 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:46.738 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:46.851 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 150 (rep:0 size:0) 18:28:46.851 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:47.025 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:28:47.025 T:1788867488 DEBUG: PushCecKeypress - added key b 18:28:47.038 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:28:47.038 T:1945120768 DEBUG: Activating window ID: 10025 18:28:47.175 T:1788867488 DEBUG: PushCecKeypress - received key b duration 150 (rep:0 size:0) 18:28:47.175 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:28:47.365 T:1945120768 DEBUG: ------ Window Deinit (Home.xml) ------ 18:28:47.404 T:1945120768 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 18:28:47.404 T:1945120768 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY 18:28:47.641 T:1945120768 DEBUG: CDirectoryProvider[]: refreshing.. 18:28:47.641 T:1858073504 DEBUG: Previous line repeats 1 times. 18:28:47.646 T:1858073504 DEBUG: Thread JobWorker start, auto delete: true 18:28:47.646 T:1945120768 DEBUG: CDirectoryProvider[]: refreshing.. 18:28:47.646 T:1858073504 ERROR: GetDirectory - Error getting 18:28:47.646 T:1945120768 DEBUG: CDirectoryProvider[]: refreshing.. 18:28:47.646 T:1763701664 DEBUG: Thread JobWorker start, auto delete: true 18:28:47.646 T:1858073504 ERROR: GetDirectory - Error getting 18:28:47.646 T:1945120768 DEBUG: CDirectoryProvider[]: refreshing.. 18:28:47.647 T:1763701664 ERROR: GetDirectory - Error getting 18:28:47.647 T:1945120768 ERROR: Previous line repeats 1 times. 18:28:47.647 T:1945120768 DEBUG: CDirectoryProvider[]: refreshing.. 18:28:47.647 T:1755313056 DEBUG: Thread JobWorker start, auto delete: true 18:28:47.647 T:1763701664 ERROR: GetDirectory - Error getting 18:28:47.647 T:1945120768 DEBUG: CDirectoryProvider[]: refreshing.. 18:28:47.647 T:1858073504 ERROR: GetDirectory - Error getting 18:28:47.648 T:1945120768 ERROR: Previous line repeats 1 times. 18:28:47.648 T:1945120768 DEBUG: CGUIMediaWindow::GetDirectory (smb://server1/videos/) 18:28:47.648 T:1945120768 DEBUG: ParentPath = [smb://server1/videos/] 18:28:47.860 T:1549808544 DEBUG: Thread BackgroundLoader start, auto delete: false 18:28:48.360 T:1549808544 DEBUG: Thread BackgroundLoader 1549808544 terminating 18:28:49.971 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:49.971 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:49.988 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:50.099 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 128 (rep:0 size:0) 18:28:50.099 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:50.209 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:50.209 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:50.221 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:50.340 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 131 (rep:0 size:0) 18:28:50.340 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:50.884 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:28:50.884 T:1788867488 DEBUG: PushCecKeypress - added key b 18:28:50.889 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:28:50.891 T:1945120768 DEBUG: CGUIMediaWindow::GetDirectory (smb://server1/videos/down/) 18:28:50.891 T:1945120768 DEBUG: ParentPath = [smb://server1/videos/] 18:28:50.960 T:1549808544 DEBUG: Thread BackgroundLoader start, auto delete: false 18:28:51.009 T:1788867488 DEBUG: PushCecKeypress - received key b duration 126 (rep:0 size:0) 18:28:51.009 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:28:51.422 T:1549808544 DEBUG: Thread BackgroundLoader 1549808544 terminating 18:28:51.975 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:51.975 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:52.016 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:52.100 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 125 (rep:0 size:0) 18:28:52.100 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:52.267 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:52.267 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:52.271 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:52.389 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 123 (rep:0 size:0) 18:28:52.390 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:52.497 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:52.498 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:52.514 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:52.620 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 122 (rep:0 size:0) 18:28:52.620 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:53.399 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:28:53.399 T:1788867488 DEBUG: PushCecKeypress - added key b 18:28:53.424 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:28:53.426 T:1945120768 DEBUG: CGUIMediaWindow::GetDirectory (smb://server1/videos/down/Game of Thrones S02 German DL 1080p BluRay x264 - GZCrew/) 18:28:53.426 T:1945120768 DEBUG: ParentPath = [smb://server1/videos/down/] 18:28:53.506 T:1945120768 DEBUG: RunQuery took 0 ms for 4 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=6 18:28:53.511 T:1549808544 DEBUG: Thread BackgroundLoader start, auto delete: false 18:28:53.530 T:1788867488 DEBUG: PushCecKeypress - received key b duration 132 (rep:0 size:0) 18:28:53.530 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:28:53.655 T:1549808544 DEBUG: Thread BackgroundLoader 1549808544 terminating 18:28:54.508 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:54.508 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:54.519 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:54.631 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 123 (rep:0 size:0) 18:28:54.631 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:54.787 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:54.787 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:54.788 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:54.910 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 124 (rep:0 size:0) 18:28:54.910 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:55.033 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:55.033 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:55.038 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:55.165 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 132 (rep:0 size:0) 18:28:55.165 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:55.292 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:55.292 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:55.315 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:55.410 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 118 (rep:0 size:0) 18:28:55.410 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:55.512 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:55.512 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:55.549 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:55.641 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 128 (rep:0 size:0) 18:28:55.641 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:55.866 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:28:55.866 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:28:55.870 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:28:55.990 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 125 (rep:0 size:0) 18:28:55.990 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:28:57.678 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:28:57.678 T:1788867488 DEBUG: PushCecKeypress - added key b 18:28:57.688 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:28:57.709 T:1945120768 DEBUG: OnPlayMedia smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:28:57.709 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 18:28:57.710 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 18:28:57.729 T:1945120768 DEBUG: Loading settings for smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:28:57.748 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers(smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv) 18:28:57.748 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 18:28:57.748 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 18:28:57.748 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 18:28:57.749 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 18:28:57.750 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 18:28:57.750 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 18:28:57.750 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 18:28:57.750 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 18:28:57.750 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 18:28:57.759 T:1945120768 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 18:28:57.759 T:1945120768 NOTICE: VideoPlayer: Opening: smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:28:57.759 T:1945120768 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 18:28:57.760 T:1945120768 DEBUG: CMMALRenderer::CMMALRenderer 18:28:57.760 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:28:57.760 T:1945120768 DEBUG: CMMALRenderer::PreInit 18:28:57.760 T:1549808544 DEBUG: Thread MMALProcess start, auto delete: false 18:28:57.760 T:1549808544 DEBUG: CMMALRenderer::Run - starting 18:28:57.760 T:1558197152 DEBUG: Thread VideoPlayer start, auto delete: false 18:28:57.761 T:1558197152 NOTICE: Creating InputStream 18:28:57.794 T:1558197152 DEBUG: CSMBFile::Open - opened smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv, fd=10000 18:28:57.797 T:1558197152 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 18:28:57.815 T:1558197152 DEBUG: ScanForExternalSubtitles: END (total time: 19 ms) 18:28:57.816 T:1558197152 NOTICE: Creating Demuxer 18:28:57.821 T:1558197152 DEBUG: Open - probing detected format [matroska,webm] 18:28:57.822 T:1558197152 DEBUG: Open - avformat_find_stream_info starting 18:28:57.839 T:1788867488 DEBUG: PushCecKeypress - received key b duration 162 (rep:0 size:0) 18:28:57.839 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:28:57.865 T:1558197152 DEBUG: Open - av_find_stream_info finished 18:28:57.865 T:1558197152 INFO: ffmpeg[5CE033A0]: Input #0, matroska,webm, smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mk': 18:28:57.865 T:1558197152 INFO: ffmpeg[5CE033A0]: Metadata: 18:28:57.865 T:1558197152 INFO: ffmpeg[5CE033A0]: encoder : libebml v1.3.0 + libmatroska v1.4.0 18:28:57.865 T:1558197152 INFO: ffmpeg[5CE033A0]: creation_time : 2013-03-30 18:27:13 18:28:57.865 T:1558197152 INFO: ffmpeg[5CE033A0]: Duration: 00:54:10.25, start: 0.000000, bitrate: 9160 kb/s 18:28:57.866 T:1558197152 INFO: ffmpeg[5CE033A0]: Stream #0:0: Video: h264 (High), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default) 18:28:57.866 T:1558197152 INFO: ffmpeg[5CE033A0]: Stream #0:1(ger): Audio: dts (DTS), 48000 Hz, 5.1(side), fltp, 768 kb/s (default) (forced) 18:28:57.866 T:1558197152 INFO: ffmpeg[5CE033A0]: Stream #0:2(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), fltp, 1536 kb/s 18:28:57.866 T:1558197152 INFO: ffmpeg[5CE033A0]: Stream #0:3(ger): Subtitle: subrip (default) 18:28:57.866 T:1558197152 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 18:28:57.866 T:1558197152 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 18:28:57.866 T:1558197152 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 18:28:57.866 T:1558197152 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3 18:28:57.867 T:1558197152 NOTICE: Opening stream: 0 source: 256 18:28:57.867 T:1558197152 NOTICE: Creating video codec with codec id: 28 18:28:57.867 T:1558197152 DEBUG: FactoryCodec - Video: mmal-xxxx - Opening 18:28:57.872 T:1558197152 DEBUG: CMMALPool::CMMALPool Created pool 0x72110ec0 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV) 18:28:57.877 T:1558197152 DEBUG: FactoryCodec - Video: mmal-h264 - Opened 18:28:57.877 T:1558197152 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 18:28:57.877 T:1558197152 NOTICE: Creating video thread 18:28:57.877 T:1533031328 DEBUG: Thread VideoPlayerVideo start, auto delete: false 18:28:57.877 T:1558197152 NOTICE: Opening stream: 1 source: 256 18:28:57.877 T:1533031328 NOTICE: running thread: video_thread 18:28:57.877 T:1558197152 NOTICE: Finding audio codec for: 86020 18:28:57.877 T:1558197152 DEBUG: FactoryCodec - Audio: passthrough - Opening 18:28:57.877 T:1558197152 DEBUG: FactoryCodec - Audio: passthrough - Failed 18:28:57.877 T:1558197152 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 18:28:57.878 T:1558197152 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 18:28:57.878 T:1558197152 NOTICE: Creating audio thread 18:28:57.878 T:1524642720 DEBUG: Thread VideoPlayerAudio start, auto delete: false 18:28:57.878 T:1558197152 NOTICE: Opening stream: 3 source: 256 18:28:57.878 T:1524642720 NOTICE: running thread: CVideoPlayerAudio::Process() 18:28:57.878 T:1558197152 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 18:28:57.878 T:1558197152 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 18:28:57.878 T:1558197152 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:28:57.879 T:1558197152 DEBUG: OnPlayBackStarted: play state was 1, starting 1 18:28:57.879 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 2 18:28:57.879 T:1945120768 DEBUG: PlayFile: OpenFile succeed, play state 2 18:28:57.879 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:28:57.879 T:1945120768 DEBUG: OnPlayBackStarted: play state was 2, starting 0 18:28:57.880 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:28:57.880 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:28:57.888 T:1524642720 DEBUG: CDVDAudio::Pause - pausing audio stream 18:28:57.889 T:1524642720 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, no pass-through) 18:28:57.889 T:1524642720 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 18:28:57.890 T:1913648032 INFO: CActiveAESink::OpenSink - initialize sink 18:28:57.890 T:1913648032 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 18:28:57.890 T:1913648032 DEBUG: CAESinkPi:Initialize Format:24 Channels:2 Samplerate:48000 framesize:8 bufsize:19200 bytes/s=384000.00 dest=PI:HDMI 18:28:57.890 T:1913648032 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 18:28:57.892 T:1913648032 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x71702240 18:28:57.893 T:1913648032 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(19200), nBufferAlignmen(16) 18:28:57.894 T:1913648032 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 18:28:57.894 T:1913648032 DEBUG: Output Device : HDMI 18:28:57.894 T:1913648032 DEBUG: Sample Rate : 48000 18:28:57.894 T:1913648032 DEBUG: Sample Format : AE_FMT_FLOATP 18:28:57.894 T:1913648032 DEBUG: Channel Count : 2 18:28:57.894 T:1913648032 DEBUG: Channel Layout: FL,FR 18:28:57.894 T:1913648032 DEBUG: Frames : 2400 18:28:57.894 T:1913648032 DEBUG: Frame Size : 8 18:28:57.900 T:1923711904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:28:57.909 T:1945117600 DEBUG: Previous line repeats 1 times. 18:28:57.909 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 18:28:57.909 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 18:28:57.909 T:1755313056 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv) 18:28:57.915 T:1558197152 DEBUG: CVideoPlayer::HandleMessages - player started 1 18:28:57.970 T:1533031328 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 23.98. format: MMAL 18:28:57.970 T:1945120768 DEBUG: DeleteRenderer - deleting renderer 18:28:57.970 T:1945120768 DEBUG: CMMALRenderer::~CMMALRenderer 18:28:57.970 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:28:57.970 T:1549808544 DEBUG: CMMALRenderer::Run - stopping 18:28:57.970 T:1549808544 DEBUG: Thread MMALProcess 1549808544 terminating 18:28:57.970 T:1945120768 DEBUG: CMMALRenderer::CMMALRenderer 18:28:57.970 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:28:57.970 T:1549808544 DEBUG: Thread MMALProcess start, auto delete: false 18:28:57.970 T:1945120768 DEBUG: CMMALRenderer::PreInit 18:28:57.970 T:1549808544 DEBUG: CMMALRenderer::Run - starting 18:28:57.970 T:1945120768 DEBUG: CMMALRenderer::Configure - 1920x1080->1920x1080@23.98 flags:12 format:18 ext:0 orient:0 18:28:57.970 T:1945120768 DEBUG: CRenderManager::Configure - 4 18:28:57.972 T:1549808544 DEBUG: CMMALRenderer::CheckConfigurationVout Changing Vout dimensions from 160x64 (160x64) to 1920x1080 (1920x1088) OPQV 18:28:57.977 T:1549808544 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,0,0 (o:0 v:0 d:0 i:0) 18:28:57.977 T:1507865504 DEBUG: Thread MMALRenderer start, auto delete: false 18:28:57.977 T:1507865504 DEBUG: CMMALRenderer::Process - starting 18:28:57.977 T:1549808544 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,0,0 t:100000 18:28:57.988 T:1945120768 DEBUG: CMMALRenderer::RenderUpdate - vsync -1 (+36576) 18:28:57.988 T:1945120768 DEBUG: Activating window ID: 12005 18:28:57.989 T:1945120768 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 18:28:57.990 T:1945120768 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 18:28:57.990 T:1945120768 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 18:28:58.004 T:1507865504 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 (o:0 v:0 d:0 i:0) 18:28:58.005 T:1507865504 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 t:100000 18:29:00.004 T:1558197152 DEBUG: CVideoPlayer::HandleMessages - player started 2 18:29:00.004 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 3 18:29:00.004 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:29:00.004 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 0 18:29:00.004 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:29:00.004 T:1558197152 DEBUG: VideoPlayer::Sync - Audio - pts: 159000.000000, cache: 259618.606407, totalcache: 500000.000000 18:29:00.004 T:1558197152 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 18:29:00.005 T:1524642720 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-100618.606407) 18:29:00.005 T:1524642720 DEBUG: CDVDAudio::Resume - resume audio stream 18:29:00.006 T:1533031328 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-100618.606407) 18:29:00.006 T:1923711904 DEBUG: CRBP::AdjustHDMIClock(1.00000) = 1.00000 18:29:00.007 T:1923711904 DEBUG: ActiveAE - start sync of audio stream 18:29:00.150 T:1923711904 DEBUG: ActiveAE::SyncStream - average error of -15.509081, start adjusting 18:29:00.151 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.509081 below threshold of 30.000000 18:29:01.489 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:02.055 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:02.085 T:1524642720 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-63328.977704, adjusted:-63328.977704 18:29:02.788 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:03.008 T:1524642720 NOTICE: CVideoPlayerAudio::Process - stream stalled 18:29:03.254 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 1 18:29:03.254 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:03.254 T:1524642720 DEBUG: CDVDAudio::Pause - pausing audio stream 18:29:03.254 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:29:26.689 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 2 18:29:26.689 T:1524642720 DEBUG: CDVDAudio::Pause - pausing audio stream 18:29:26.689 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:29:26.689 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 3 18:29:26.690 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:29:26.690 T:1524642720 DEBUG: CDVDAudio::Resume - resume audio stream 18:29:26.690 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:29:26.692 T:1923711904 DEBUG: ActiveAE - start sync of audio stream 18:29:26.746 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:26.835 T:1923711904 DEBUG: Previous line repeats 3 times. 18:29:26.835 T:1923711904 DEBUG: ActiveAE::SyncStream - average error of -1650.696633, start adjusting 18:29:26.851 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:27.185 T:1923711904 DEBUG: Previous line repeats 13 times. 18:29:27.185 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.165367 below threshold of 30.000000 18:29:27.196 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:27.788 T:1533031328 DEBUG: Previous line repeats 30 times. 18:29:27.788 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:29:27.803 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:27.914 T:1858073504 DEBUG: Previous line repeats 4 times. 18:29:27.914 T:1858073504 DEBUG: Thread JobWorker 1858073504 terminating (autodelete) 18:29:27.915 T:1763701664 DEBUG: Thread JobWorker 1763701664 terminating (autodelete) 18:29:27.950 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:29:27.995 T:1755313056 DEBUG: Thread JobWorker 1755313056 terminating (autodelete) 18:29:28.095 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:28.198 T:1923711904 DEBUG: Previous line repeats 5 times. 18:29:28.198 T:1923711904 DEBUG: ActiveAE::SyncStream - average error 214.931648 above threshold of 100.000000 18:29:28.210 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:28.348 T:1923711904 DEBUG: Previous line repeats 7 times. 18:29:28.348 T:1923711904 DEBUG: ActiveAE::SyncStream - average error 14.931648, last average error: 214.931648 18:29:28.360 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:28.497 T:1923711904 DEBUG: Previous line repeats 7 times. 18:29:28.497 T:1923711904 DEBUG: ActiveAE::SyncStream - average error of 6.276128, start adjusting 18:29:28.497 T:1923711904 DEBUG: ActiveAE::SyncStream - average error 0.276128 below threshold of 30.000000 18:29:28.511 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:29.109 T:1533031328 DEBUG: Previous line repeats 31 times. 18:29:29.110 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:29:29.153 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:29.155 T:1533031328 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333 18:29:29.155 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:29.821 T:1533031328 DEBUG: Previous line repeats 4 times. 18:29:29.821 T:1533031328 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 18:29:34.205 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:36.004 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:36.027 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -389.823630 above threshold of 100.000000 18:29:36.497 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.823630 below threshold of 30.000000 18:29:37.182 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:37.843 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:37.909 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -2178.872261 above threshold of 100.000000 18:29:39.201 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:40.805 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:42.684 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:43.090 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:43.982 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:44.153 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:45.197 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:46.594 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:47.760 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:47.987 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:48.621 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:50.288 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:51.698 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:53.067 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:55.095 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:55.411 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:57.248 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:58.163 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:29:58.338 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:29:58.339 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:29:58.424 T:1533031328 DEBUG: OutputPicture - dropped in output 18:29:59.256 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:29:59.665 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:00.262 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:00.563 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:01.024 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:02.855 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:03.316 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:03.721 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:04.676 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:06.534 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:07.335 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:09.807 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:10.190 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:10.190 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:10.206 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 18:30:10.211 T:1945120768 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 18:30:10.220 T:1945120768 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 18:30:10.220 T:1945120768 INFO: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: KEEP_IN_MEMORY 18:30:10.254 T:1945120768 DEBUG: CMMALRenderer::RenderUpdate - vsync 40909 (+1) 18:30:10.358 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 169 (rep:0 size:0) 18:30:10.358 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:10.546 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:10.546 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:10.554 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 18:30:10.719 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 173 (rep:0 size:0) 18:30:10.719 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:11.108 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:11.318 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:11.318 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:11.321 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 18:30:11.326 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 18:30:11.326 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 18:30:11.488 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 170 (rep:0 size:0) 18:30:11.488 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:11.656 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:11.656 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:11.671 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 18:30:11.780 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 124 (rep:0 size:0) 18:30:11.780 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:12.013 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:12.013 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:12.021 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 18:30:12.139 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 126 (rep:0 size:0) 18:30:12.139 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:12.561 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:12.561 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:12.571 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 18:30:12.688 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 127 (rep:0 size:0) 18:30:12.688 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:13.019 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:13.019 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:13.021 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is StepForward 18:30:13.066 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:13.066 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 2 18:30:13.066 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:30:13.067 T:1558197152 DEBUG: demuxer seek to: 79462.000000 18:30:13.067 T:1524642720 DEBUG: CDVDAudio::Pause - pausing audio stream 18:30:13.077 T:1558197152 DEBUG: SeekTime - seek ended up on time 81498 18:30:13.077 T:1558197152 DEBUG: demuxer seek to: 79462.000000, success 18:30:13.077 T:1558197152 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 18:30:13.149 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 130 (rep:0 size:0) 18:30:13.149 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:13.163 T:1524642720 DEBUG: CDVDAudio::Flush - flush audio stream 18:30:13.163 T:1524642720 DEBUG: CDVDAudio::Pause - pausing audio stream 18:30:13.164 T:1524642720 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 18:30:13.164 T:1533031328 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 18:30:13.258 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:13.678 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:13.792 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 18:30:13.792 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 18:30:15.318 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:15.319 T:1558197152 DEBUG: demuxer seek to: 379462.000000 18:30:15.319 T:1558197152 DEBUG: SeekTime - seek ended up on time 383216 18:30:15.319 T:1558197152 DEBUG: demuxer seek to: 379462.000000, success 18:30:15.319 T:1558197152 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 18:30:15.363 T:1524642720 DEBUG: CDVDAudio::Flush - flush audio stream 18:30:15.363 T:1524642720 DEBUG: CDVDAudio::Pause - pausing audio stream 18:30:15.363 T:1524642720 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 18:30:15.364 T:1533031328 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 18:30:15.523 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:15.816 T:1558197152 DEBUG: CVideoPlayer::HandleMessages - player started 1 18:30:15.825 T:1558197152 DEBUG: CVideoPlayer::HandleMessages - player started 2 18:30:15.825 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 3 18:30:15.825 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:30:15.825 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 0 18:30:15.825 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:30:15.825 T:1558197152 DEBUG: VideoPlayer::Sync - Audio - pts: 383392000.000000, cache: 279811.890488, totalcache: 500000.000000 18:30:15.825 T:1558197152 DEBUG: VideoPlayer::Sync - Video - pts: 383216000.000000, cache: 50000.000000, totalcache: 100000.000000 18:30:15.825 T:1533031328 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(383112188.109512) 18:30:15.825 T:1524642720 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(383112188.109512) 18:30:15.825 T:1524642720 DEBUG: CDVDAudio::Resume - resume audio stream 18:30:15.827 T:1923711904 DEBUG: ActiveAE - start sync of audio stream 18:30:15.964 T:1923711904 DEBUG: ActiveAE::SyncStream - average error of -0.079359, start adjusting 18:30:15.964 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.079359 below threshold of 30.000000 18:30:17.005 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:17.578 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:17.592 T:1524642720 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-70661.966553, adjusted:-70661.966553 18:30:18.003 T:1533031328 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:30:18.294 T:1945120768 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 18:30:18.295 T:1945120768 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 18:30:18.825 T:1524642720 NOTICE: CVideoPlayerAudio::Process - stream stalled 18:30:19.154 T:1533031328 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:30:19.154 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 1 18:30:19.154 T:1524642720 DEBUG: CDVDAudio::Pause - pausing audio stream 18:30:19.154 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:30:32.047 T:1788867488 DEBUG: PushCecKeypress - received key e0 duration 1000 (rep:0 size:0) 18:30:32.047 T:1788867488 DEBUG: PushCecKeypress - added key e0 18:30:32.054 T:1945120768 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 18:30:32.054 T:1945120768 NOTICE: CVideoPlayer::CloseFile() 18:30:32.054 T:1945120768 NOTICE: VideoPlayer: waiting for threads to exit 18:30:33.421 T:1558197152 NOTICE: CVideoPlayer::OnExit() 18:30:33.421 T:1558197152 DEBUG: CVideoPlayer::SetCaching - caching state 0 18:30:33.421 T:1558197152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:30:33.421 T:1558197152 NOTICE: Closing stream player 1 18:30:33.421 T:1558197152 NOTICE: Waiting for audio thread to exit 18:30:33.421 T:1524642720 DEBUG: CDVDAudio::Resume - resume audio stream 18:30:33.421 T:1533031328 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:30:33.421 T:1524642720 NOTICE: thread end: CVideoPlayerAudio::OnExit() 18:30:33.421 T:1524642720 DEBUG: Thread VideoPlayerAudio 1524642720 terminating 18:30:33.422 T:1558197152 NOTICE: Closing audio device 18:30:33.463 T:1558197152 DEBUG: CDVDAudio::Flush - flush audio stream 18:30:33.464 T:1923711904 DEBUG: CActiveAE::DiscardStream - audio stream deleted 18:30:33.465 T:1923711904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:30:33.468 T:1558197152 DEBUG: Previous line repeats 2 times. 18:30:33.468 T:1558197152 NOTICE: Deleting audio codec 18:30:33.468 T:1558197152 NOTICE: Closing stream player 2 18:30:33.468 T:1558197152 NOTICE: waiting for video thread to exit 18:30:33.470 T:1533031328 NOTICE: thread end: video_thread 18:30:33.470 T:1533031328 DEBUG: Thread VideoPlayerVideo 1533031328 terminating 18:30:33.470 T:1558197152 NOTICE: deleting video codec 18:30:33.490 T:1558197152 NOTICE: Closing stream player 3 18:30:33.492 T:1558197152 DEBUG: CSMBFile::Close closing fd 10000 18:30:33.493 T:1558197152 DEBUG: OnPlayBackStopped: play state was 2, starting 0 18:30:33.494 T:1558197152 DEBUG: Thread VideoPlayer 1558197152 terminating 18:30:33.495 T:1945120768 NOTICE: VideoPlayer: finished waiting 18:30:33.495 T:1945120768 DEBUG: DeleteRenderer - deleting renderer 18:30:33.495 T:1945120768 DEBUG: CMMALRenderer::~CMMALRenderer 18:30:33.495 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:30:33.513 T:1913648032 INFO: CActiveAESink::OpenSink - initialize sink 18:30:33.514 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 18:30:33.514 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 18:30:33.537 T:1507865504 DEBUG: CMMALRenderer::Process - stopping 18:30:33.537 T:1507865504 DEBUG: Thread MMALRenderer 1507865504 terminating 18:30:33.556 T:1945120768 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x72110ec0 for port vc.ril.video_decode:out:0(OPQV) 18:30:33.561 T:1549808544 DEBUG: CMMALRenderer::Run - stopping 18:30:33.561 T:1549808544 DEBUG: Thread MMALProcess 1549808544 terminating 18:30:33.561 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:30:33.562 T:1945120768 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 18:30:33.584 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:30:33.601 T:1945120768 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 18:30:33.605 T:1945120768 DEBUG: CGUIMediaWindow::GetDirectory (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/) 18:30:33.605 T:1945120768 DEBUG: ParentPath = [smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/] 18:30:33.612 T:1913648032 DEBUG: CAESinkPi:Drain delay:99ms now:0ms 18:30:33.613 T:1913648032 DEBUG: CAESinkPi:Deinitialize 18:30:33.613 T:1913648032 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000 18:30:33.622 T:1913648032 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x71702240 18:30:33.623 T:1913648032 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 18:30:33.623 T:1913648032 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI 18:30:33.623 T:1913648032 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 18:30:33.624 T:1913648032 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x71702240 18:30:33.625 T:1913648032 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignmen(16) 18:30:33.625 T:1913648032 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 18:30:33.626 T:1913648032 DEBUG: Output Device : HDMI 18:30:33.626 T:1913648032 DEBUG: Sample Rate : 44100 18:30:33.626 T:1913648032 DEBUG: Sample Format : AE_FMT_FLOAT 18:30:33.626 T:1913648032 DEBUG: Channel Count : 2 18:30:33.626 T:1913648032 DEBUG: Channel Layout: FL,FR 18:30:33.626 T:1913648032 DEBUG: Frames : 2205 18:30:33.626 T:1913648032 DEBUG: Frame Size : 8 18:30:33.629 T:1923711904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:30:33.672 T:1945120768 DEBUG: Previous line repeats 1 times. 18:30:33.672 T:1945120768 DEBUG: RunQuery took 1 ms for 4 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=6 18:30:33.675 T:1549808544 DEBUG: Thread BackgroundLoader start, auto delete: false 18:30:33.696 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x2dbb098 18:30:33.696 T:1945120768 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(314128), nBufferAlignmen(16) 18:30:33.699 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x2ce0be8 18:30:33.701 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x1f54628 18:30:33.701 T:1945120768 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16) 18:30:33.775 T:1549808544 DEBUG: Thread BackgroundLoader 1549808544 terminating 18:30:33.852 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x2dbb098 18:30:33.853 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x2ce0be8 18:30:33.854 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x1f54628 18:30:33.854 T:1945120768 DEBUG: DecodeJpegToTexture: decoded special://skin/extras/backgrounds/primary.jpg 1280x720 18:30:33.861 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x2d89218 18:30:33.861 T:1945120768 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(93824), nBufferAlignmen(16) 18:30:33.863 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x2d910c0 18:30:33.864 T:1945120768 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16) 18:30:33.877 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x2d89218 18:30:33.878 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x2d910c0 18:30:33.878 T:1945120768 DEBUG: DecodeJpegToTexture: decoded special://skin/extras/backgrounds/pattern1.jpg 900x506 18:30:33.939 T:1945120768 NOTICE: CVideoPlayer::CloseFile() 18:30:33.939 T:1945120768 NOTICE: VideoPlayer: waiting for threads to exit 18:30:33.939 T:1945120768 NOTICE: VideoPlayer: finished waiting 18:30:33.939 T:1945120768 NOTICE: CVideoPlayer::CloseFile() 18:30:33.939 T:1945120768 NOTICE: VideoPlayer: waiting for threads to exit 18:30:33.939 T:1945120768 NOTICE: VideoPlayer: finished waiting 18:30:33.942 T:1945120768 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 18:30:33.943 T:1549808544 DEBUG: Thread JobWorker start, auto delete: true 18:30:33.943 T:1549808544 DEBUG: DoWork - Saving file state for video item smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:30:34.003 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 18:30:34.003 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 18:30:35.954 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 0 (rep:0 size:0) 18:30:35.954 T:1788867488 DEBUG: PushCecKeypress - added key ed 18:30:35.962 T:1945120768 DEBUG: OnKey: 237 (0xed, obc18) pressed, action is PreviousMenu 18:30:35.962 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:30:36.214 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 260 (rep:0 size:0) 18:30:36.214 T:1788867488 DEBUG: PushCecKeypress - ignored key ed 18:30:36.305 T:1945120768 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 18:30:36.308 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:30:36.308 T:1945120768 DEBUG: ------ Window Init (Home.xml) ------ 18:30:36.308 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 18:30:36.308 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 18:30:36.309 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 18:30:36.309 T:1516254112 DEBUG: Thread JobWorker start, auto delete: true 18:30:36.309 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 18:30:36.309 T:1541419936 DEBUG: Thread JobWorker start, auto delete: true 18:30:38.080 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 0 (rep:0 size:0) 18:30:38.080 T:1788867488 DEBUG: PushCecKeypress - added key a9 18:30:38.114 T:1945120768 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:30:38.294 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 215 (rep:0 size:0) 18:30:38.295 T:1788867488 DEBUG: PushCecKeypress - ignored key a9 18:30:39.071 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 0 (rep:0 size:0) 18:30:39.071 T:1788867488 DEBUG: PushCecKeypress - added key a9 18:30:39.088 T:1945120768 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:30:39.285 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 215 (rep:0 size:0) 18:30:39.286 T:1788867488 DEBUG: PushCecKeypress - ignored key a9 18:30:39.881 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0) 18:30:39.881 T:1788867488 DEBUG: PushCecKeypress - added key a6 18:30:39.922 T:1945120768 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:30:40.005 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 125 (rep:0 size:0) 18:30:40.006 T:1788867488 DEBUG: PushCecKeypress - ignored key a6 18:30:40.394 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0) 18:30:40.394 T:1788867488 DEBUG: PushCecKeypress - added key a6 18:30:40.437 T:1945120768 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:30:40.566 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 172 (rep:0 size:0) 18:30:40.566 T:1788867488 DEBUG: PushCecKeypress - ignored key a6 18:30:42.108 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 0 (rep:0 size:0) 18:30:42.109 T:1788867488 DEBUG: PushCecKeypress - added key a9 18:30:42.118 T:1945120768 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:30:42.326 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 217 (rep:0 size:0) 18:30:42.326 T:1788867488 DEBUG: PushCecKeypress - ignored key a9 18:30:43.006 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0) 18:30:43.006 T:1788867488 DEBUG: PushCecKeypress - added key a6 18:30:43.048 T:1945120768 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:30:43.226 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 221 (rep:0 size:0) 18:30:43.226 T:1788867488 DEBUG: PushCecKeypress - ignored key a6 18:30:44.102 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:44.102 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:44.116 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:30:44.276 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 174 (rep:0 size:0) 18:30:44.276 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:44.652 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:30:44.652 T:1788867488 DEBUG: PushCecKeypress - added key b 18:30:44.668 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:30:44.669 T:1945120768 DEBUG: Activating window ID: 10004 18:30:44.776 T:1788867488 DEBUG: PushCecKeypress - received key b duration 124 (rep:0 size:0) 18:30:44.776 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:30:44.984 T:1945120768 DEBUG: ------ Window Deinit (Home.xml) ------ 18:30:44.988 T:1945120768 DEBUG: ------ Window Init (Settings.xml) ------ 18:30:50.103 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0) 18:30:50.103 T:1788867488 DEBUG: PushCecKeypress - added key a6 18:30:50.108 T:1945120768 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:30:50.228 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 125 (rep:0 size:0) 18:30:50.228 T:1788867488 DEBUG: PushCecKeypress - ignored key a6 18:30:50.695 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:50.695 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:50.712 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:30:50.818 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 123 (rep:0 size:0) 18:30:50.818 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:30:51.083 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:30:51.084 T:1788867488 DEBUG: PushCecKeypress - added key b 18:30:51.098 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:30:51.098 T:1945120768 DEBUG: Activating window ID: 10016 18:30:51.258 T:1788867488 DEBUG: PushCecKeypress - received key b duration 174 (rep:0 size:0) 18:30:51.258 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:30:51.416 T:1945120768 DEBUG: ------ Window Deinit (Settings.xml) ------ 18:30:51.437 T:1945120768 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 18:30:53.985 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0) 18:30:53.985 T:1788867488 DEBUG: PushCecKeypress - added key a6 18:30:54.010 T:1945120768 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:30:54.159 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 173 (rep:0 size:0) 18:30:54.159 T:1788867488 DEBUG: PushCecKeypress - ignored key a6 18:30:55.059 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:30:55.059 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:30:55.071 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:30:55.229 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 171 (rep:0 size:0) 18:30:55.229 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:31:02.483 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:31:02.483 T:1788867488 DEBUG: PushCecKeypress - added key b 18:31:02.487 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:31:02.488 T:1945120768 NOTICE: Disabled debug logging due to GUI setting. Level 0. 18:31:02.488 T:1945120768 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 18:32:08.354 T:1945120768 NOTICE: Samba is idle. Closing the remaining connections 18:32:18.037 T:1945120768 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 18:32:18.037 T:1945120768 NOTICE: Enabled debug logging due to GUI setting. Level 2. 18:32:18.039 T:1945120768 DEBUG: ------ Window Init () ------ 18:32:18.191 T:1788867488 DEBUG: PushCecKeypress - received key b duration 156 (rep:0 size:0) 18:32:18.191 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:32:24.266 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 0 (rep:0 size:0) 18:32:24.266 T:1788867488 DEBUG: PushCecKeypress - added key ed 18:32:24.270 T:1945120768 DEBUG: OnKey: 237 (0xed, obc18) pressed, action is PreviousMenu 18:32:24.294 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:32:24.430 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 165 (rep:0 size:0) 18:32:24.430 T:1788867488 DEBUG: PushCecKeypress - ignored key ed 18:32:24.582 T:1945120768 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 18:32:24.587 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:32:24.587 T:1945120768 DEBUG: ------ Window Init (Settings.xml) ------ 18:32:27.921 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 0 (rep:0 size:0) 18:32:27.921 T:1788867488 DEBUG: PushCecKeypress - added key ed 18:32:27.952 T:1945120768 DEBUG: OnKey: 237 (0xed, obc18) pressed, action is PreviousMenu 18:32:27.952 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:32:28.051 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 130 (rep:0 size:0) 18:32:28.052 T:1788867488 DEBUG: PushCecKeypress - ignored key ed 18:32:28.267 T:1945120768 DEBUG: ------ Window Deinit (Settings.xml) ------ 18:32:28.287 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:32:28.287 T:1945120768 DEBUG: ------ Window Init (Home.xml) ------ 18:32:29.599 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:32:29.599 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:32:29.606 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:32:29.772 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 173 (rep:0 size:0) 18:32:29.772 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:32:30.214 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:32:30.214 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:32:30.240 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:32:30.342 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 128 (rep:0 size:0) 18:32:30.342 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:32:30.791 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:32:30.792 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:32:30.834 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:32:30.986 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 195 (rep:0 size:0) 18:32:30.986 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:32:31.618 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:32:31.618 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:32:31.634 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:32:31.768 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 149 (rep:0 size:0) 18:32:31.768 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:32:32.103 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0) 18:32:32.103 T:1788867488 DEBUG: PushCecKeypress - added key a7 18:32:32.143 T:1945120768 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 18:32:32.225 T:1788867488 DEBUG: PushCecKeypress - received key a7 duration 122 (rep:0 size:0) 18:32:32.225 T:1788867488 DEBUG: PushCecKeypress - ignored key a7 18:32:32.590 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:32:32.590 T:1788867488 DEBUG: PushCecKeypress - added key b 18:32:32.604 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:32:32.604 T:1945120768 DEBUG: Activating window ID: 10025 18:32:32.713 T:1788867488 DEBUG: PushCecKeypress - received key b duration 123 (rep:0 size:0) 18:32:32.713 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:32:32.915 T:1945120768 DEBUG: ------ Window Deinit (Home.xml) ------ 18:32:32.937 T:1945120768 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 18:32:32.940 T:1945120768 DEBUG: CGUIMediaWindow::GetDirectory (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/) 18:32:32.940 T:1945120768 DEBUG: ParentPath = [smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/] 18:32:32.948 T:1945120768 DEBUG: OpenDir: Using authentication url smb://server1/videos/down/Game%20of%20Videos%20S02%20German%20DL%201080p%20BluRay%20x264%20-%20GZCrew 18:32:33.145 T:1945120768 DEBUG: RunQuery took 0 ms for 5 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=6 18:32:33.148 T:1516254112 DEBUG: Thread BackgroundLoader start, auto delete: false 18:32:33.265 T:1516254112 DEBUG: Thread BackgroundLoader 1516254112 terminating 18:32:34.408 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:32:34.408 T:1788867488 DEBUG: PushCecKeypress - added key b 18:32:34.409 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:32:34.409 T:1945120768 INFO: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY 18:32:34.422 T:1945120768 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 18:32:34.533 T:1788867488 DEBUG: PushCecKeypress - received key b duration 124 (rep:0 size:0) 18:32:34.533 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:32:36.108 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:32:36.109 T:1788867488 DEBUG: PushCecKeypress - added key b 18:32:36.148 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:32:36.233 T:1788867488 DEBUG: PushCecKeypress - received key b duration 125 (rep:0 size:0) 18:32:36.233 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:32:36.396 T:1945120768 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 18:32:36.422 T:1945120768 DEBUG: OnPlayMedia smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:32:36.422 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 18:32:36.423 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 18:32:36.448 T:1945117600 DEBUG: GetMovieId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMovie from movie where idFile=13 18:32:36.449 T:1945117600 DEBUG: GetEpisodeId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idEpisode from episode where idFile=13 18:32:36.450 T:1945117600 DEBUG: GetMusicVideoId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMVideo from musicvideo where idFile=13 18:32:36.450 T:1945120768 DEBUG: Loading settings for smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:32:36.456 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 18:32:36.456 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 18:32:36.474 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers(smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv) 18:32:36.474 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 18:32:36.474 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 18:32:36.474 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 18:32:36.475 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 18:32:36.476 T:1945120768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 18:32:36.476 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 18:32:36.476 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 18:32:36.476 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 18:32:36.476 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 18:32:36.476 T:1945120768 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 18:32:36.485 T:1945120768 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 18:32:36.486 T:1945120768 NOTICE: VideoPlayer: Opening: smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:32:36.486 T:1945120768 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 18:32:36.486 T:1945120768 DEBUG: CMMALRenderer::CMMALRenderer 18:32:36.486 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:32:36.486 T:1945120768 DEBUG: CMMALRenderer::PreInit 18:32:36.486 T:1516254112 DEBUG: Thread MMALProcess start, auto delete: false 18:32:36.486 T:1516254112 DEBUG: CMMALRenderer::Run - starting 18:32:36.486 T:1549808544 DEBUG: Thread VideoPlayer start, auto delete: false 18:32:36.487 T:1549808544 NOTICE: Creating InputStream 18:32:36.489 T:1549808544 DEBUG: OpenDir: Using authentication url smb://server1/videos/down/Game%20of%20Videos%20S02%20German%20DL%201080p%20BluRay%20x264%20-%20GZCrew 18:32:36.511 T:1549808544 DEBUG: Previous line repeats 1 times. 18:32:36.511 T:1549808544 DEBUG: OpenDir: Using authentication url smb://server1/videos/down/Game%20of%20Videos%20S02%20German%20DL%201080p%20BluRay%20x264%20-%20GZCrew/subs 18:32:36.538 T:1549808544 DEBUG: CSMBFile::Open - opened smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv, fd=10000 18:32:36.544 T:1549808544 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 18:32:36.546 T:1549808544 DEBUG: OpenDir: Using authentication url smb://server1/videos/down/Game%20of%20Videos%20S02%20German%20DL%201080p%20BluRay%20x264%20-%20GZCrew 18:32:36.557 T:1549808544 DEBUG: OpenDir: Using authentication url smb://server1/videos/down/Game%20of%20Videos%20S02%20German%20DL%201080p%20BluRay%20x264%20-%20GZCrew/subs 18:32:36.579 T:1549808544 DEBUG: ScanForExternalSubtitles: END (total time: 35 ms) 18:32:36.579 T:1549808544 NOTICE: Creating Demuxer 18:32:36.587 T:1549808544 DEBUG: Open - probing detected format [matroska,webm] 18:32:36.588 T:1549808544 DEBUG: Open - avformat_find_stream_info starting 18:32:36.651 T:1549808544 DEBUG: Open - av_find_stream_info finished 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: Input #0, matroska,webm, smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mk': 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: Metadata: 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: encoder : libebml v1.3.0 + libmatroska v1.4.0 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: creation_time : 2013-03-30 18:27:13 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: Duration: 00:54:10.25, start: 0.000000, bitrate: 9160 kb/s 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: Stream #0:0: Video: h264 (High), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default) 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: Stream #0:1(ger): Audio: dts (DTS), 48000 Hz, 5.1(side), fltp, 768 kb/s (default) (forced) 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: Stream #0:2(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), fltp, 1536 kb/s 18:32:36.652 T:1549808544 INFO: ffmpeg[5C6033A0]: Stream #0:3(ger): Subtitle: subrip (default) 18:32:36.652 T:1549808544 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 18:32:36.652 T:1549808544 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 18:32:36.653 T:1549808544 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 18:32:36.653 T:1549808544 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3 18:32:36.653 T:1549808544 NOTICE: Opening stream: 0 source: 256 18:32:36.653 T:1549808544 NOTICE: Creating video codec with codec id: 28 18:32:36.653 T:1549808544 DEBUG: FactoryCodec - Video: mmal-xxxx - Opening 18:32:36.658 T:1549808544 DEBUG: CMMALPool::CMMALPool Created pool 0x2d6bba0 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV) 18:32:36.662 T:1549808544 DEBUG: FactoryCodec - Video: mmal-h264 - Opened 18:32:36.662 T:1549808544 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 18:32:36.662 T:1549808544 NOTICE: Creating video thread 18:32:36.662 T:1507865504 DEBUG: Thread VideoPlayerVideo start, auto delete: false 18:32:36.662 T:1549808544 NOTICE: Opening stream: 1 source: 256 18:32:36.662 T:1507865504 NOTICE: running thread: video_thread 18:32:36.662 T:1549808544 NOTICE: Finding audio codec for: 86020 18:32:36.662 T:1549808544 DEBUG: FactoryCodec - Audio: passthrough - Opening 18:32:36.662 T:1549808544 DEBUG: FactoryCodec - Audio: passthrough - Failed 18:32:36.662 T:1549808544 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 18:32:36.663 T:1549808544 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 18:32:36.663 T:1549808544 NOTICE: Creating audio thread 18:32:36.663 T:1549808544 NOTICE: Opening stream: 3 source: 256 18:32:36.663 T:1558197152 DEBUG: Thread VideoPlayerAudio start, auto delete: false 18:32:36.663 T:1549808544 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 18:32:36.663 T:1549808544 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 18:32:36.663 T:1558197152 NOTICE: running thread: CVideoPlayerAudio::Process() 18:32:36.663 T:1549808544 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:32:36.664 T:1549808544 DEBUG: Process - Start position set to last stopped position: 386000 18:32:36.672 T:1549808544 DEBUG: SeekTime - seek ended up on time 388221 18:32:36.672 T:1549808544 DEBUG: Process - starting demuxer from: 386000 18:32:36.672 T:1549808544 DEBUG: OnPlayBackStarted: play state was 1, starting 1 18:32:36.672 T:1549808544 DEBUG: CVideoPlayer::SetCaching - caching state 2 18:32:36.672 T:1945120768 DEBUG: PlayFile: OpenFile succeed, play state 2 18:32:36.672 T:1549808544 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:32:36.673 T:1945120768 DEBUG: OnPlayBackStarted: play state was 2, starting 0 18:32:36.679 T:1558197152 DEBUG: CDVDAudio::Pause - pausing audio stream 18:32:36.687 T:1763701664 DEBUG: Thread JobWorker start, auto delete: true 18:32:36.692 T:1763701664 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv) 18:32:36.700 T:1945117600 DEBUG: GetMovieId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMovie from movie where idFile=13 18:32:36.700 T:1945117600 DEBUG: GetEpisodeId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idEpisode from episode where idFile=13 18:32:36.701 T:1945117600 DEBUG: GetMusicVideoId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMVideo from musicvideo where idFile=13 18:32:36.704 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 18:32:36.704 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 18:32:36.705 T:1763701664 DEBUG: GetMovieId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMovie from movie where idFile=13 18:32:36.706 T:1763701664 DEBUG: GetEpisodeId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idEpisode from episode where idFile=13 18:32:36.707 T:1763701664 DEBUG: GetMusicVideoId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMVideo from musicvideo where idFile=13 18:32:36.745 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:32:36.746 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:32:36.755 T:1558197152 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, no pass-through) 18:32:36.756 T:1558197152 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 18:32:36.756 T:1913648032 INFO: CActiveAESink::OpenSink - initialize sink 18:32:36.756 T:1913648032 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 18:32:36.756 T:1913648032 DEBUG: CAESinkPi:Initialize Format:24 Channels:2 Samplerate:48000 framesize:8 bufsize:19200 bytes/s=384000.00 dest=PI:HDMI 18:32:36.757 T:1913648032 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 18:32:36.759 T:1913648032 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x7170c9b0 18:32:36.760 T:1913648032 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(19200), nBufferAlignmen(16) 18:32:36.761 T:1913648032 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 18:32:36.761 T:1913648032 DEBUG: Output Device : HDMI 18:32:36.761 T:1913648032 DEBUG: Sample Rate : 48000 18:32:36.761 T:1913648032 DEBUG: Sample Format : AE_FMT_FLOATP 18:32:36.761 T:1913648032 DEBUG: Channel Count : 2 18:32:36.761 T:1913648032 DEBUG: Channel Layout: FL,FR 18:32:36.761 T:1913648032 DEBUG: Frames : 2400 18:32:36.761 T:1913648032 DEBUG: Frame Size : 8 18:32:36.767 T:1923711904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:32:36.781 T:1549808544 DEBUG: Previous line repeats 1 times. 18:32:36.781 T:1549808544 DEBUG: CVideoPlayer::HandleMessages - player started 1 18:32:36.802 T:1507865504 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 23.98. format: MMAL 18:32:36.810 T:1945120768 DEBUG: DeleteRenderer - deleting renderer 18:32:36.810 T:1945120768 DEBUG: CMMALRenderer::~CMMALRenderer 18:32:36.810 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:32:36.810 T:1516254112 DEBUG: CMMALRenderer::Run - stopping 18:32:36.810 T:1516254112 DEBUG: Thread MMALProcess 1516254112 terminating 18:32:36.810 T:1945120768 DEBUG: CMMALRenderer::CMMALRenderer 18:32:36.811 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:32:36.811 T:1755313056 DEBUG: Thread MMALProcess start, auto delete: false 18:32:36.811 T:1945120768 DEBUG: CMMALRenderer::PreInit 18:32:36.811 T:1755313056 DEBUG: CMMALRenderer::Run - starting 18:32:36.812 T:1945120768 DEBUG: CMMALRenderer::Configure - 1920x1080->1920x1080@23.98 flags:12 format:18 ext:0 orient:0 18:32:36.812 T:1945120768 DEBUG: CRenderManager::Configure - 4 18:32:36.814 T:1755313056 DEBUG: CMMALRenderer::CheckConfigurationVout Changing Vout dimensions from 160x64 (160x64) to 1920x1080 (1920x1088) OPQV 18:32:36.819 T:1755313056 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,0,0 (o:0 v:0 d:0 i:0) 18:32:36.819 T:1533031328 DEBUG: Thread MMALRenderer start, auto delete: false 18:32:36.819 T:1533031328 DEBUG: CMMALRenderer::Process - starting 18:32:36.819 T:1755313056 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,0,0 t:100000 18:32:36.820 T:1945120768 DEBUG: CMMALRenderer::RenderUpdate - vsync -1 (+49706) 18:32:36.820 T:1945120768 DEBUG: Activating window ID: 12005 18:32:36.822 T:1945120768 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 18:32:36.823 T:1945120768 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 18:32:36.824 T:1945120768 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 (o:0 v:0 d:0 i:0) 18:32:36.824 T:1945120768 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 t:100000 18:32:38.583 T:1549808544 DEBUG: CVideoPlayer::HandleMessages - player started 2 18:32:38.583 T:1549808544 DEBUG: CVideoPlayer::SetCaching - caching state 3 18:32:38.583 T:1549808544 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:32:38.583 T:1549808544 DEBUG: CVideoPlayer::SetCaching - caching state 0 18:32:38.583 T:1549808544 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:32:38.583 T:1549808544 DEBUG: VideoPlayer::Sync - Audio - pts: 388383000.000000, cache: 257175.586073, totalcache: 500000.000000 18:32:38.583 T:1549808544 DEBUG: VideoPlayer::Sync - Video - pts: 388221000.000000, cache: 50000.000000, totalcache: 100000.000000 18:32:38.584 T:1558197152 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(388121000.000000) 18:32:38.584 T:1507865504 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(388121000.000000) 18:32:38.584 T:1558197152 DEBUG: CDVDAudio::Resume - resume audio stream 18:32:38.585 T:1923711904 DEBUG: ActiveAE - start sync of audio stream 18:32:38.718 T:1923711904 DEBUG: ActiveAE::SyncStream - average error of -1.618010, start adjusting 18:32:38.718 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.618010 below threshold of 30.000000 18:32:39.976 T:1558197152 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29875.848063, adjusted:-29875.848063 18:32:40.387 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:32:41.552 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:32:41.565 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -483.116564 above threshold of 100.000000 18:32:41.648 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.116564 below threshold of 30.000000 18:32:41.651 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:32:41.710 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:32:41.737 T:1558197152 NOTICE: CVideoPlayerAudio::Process - stream stalled 18:32:42.146 T:1549808544 DEBUG: CVideoPlayer::SetCaching - caching state 1 18:32:42.147 T:1558197152 DEBUG: CDVDAudio::Pause - pausing audio stream 18:32:42.147 T:1549808544 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:32:42.147 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:06.767 T:1763701664 DEBUG: Previous line repeats 3 times. 18:33:06.767 T:1763701664 DEBUG: Thread JobWorker 1763701664 terminating (autodelete) 18:33:14.246 T:1549808544 DEBUG: CVideoPlayer::SetCaching - caching state 2 18:33:14.246 T:1558197152 DEBUG: CDVDAudio::Pause - pausing audio stream 18:33:14.246 T:1549808544 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:33:14.246 T:1549808544 DEBUG: CVideoPlayer::SetCaching - caching state 3 18:33:14.246 T:1558197152 DEBUG: CDVDAudio::Resume - resume audio stream 18:33:14.246 T:1549808544 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:33:14.246 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:14.246 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:14.248 T:1923711904 DEBUG: ActiveAE - start sync of audio stream 18:33:14.267 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:14.383 T:1923711904 DEBUG: Previous line repeats 6 times. 18:33:14.383 T:1923711904 DEBUG: ActiveAE::SyncStream - average error of -1487.330328, start adjusting 18:33:14.393 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:14.733 T:1923711904 DEBUG: Previous line repeats 21 times. 18:33:14.733 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -2.690998 below threshold of 30.000000 18:33:14.743 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:14.909 T:1507865504 DEBUG: Previous line repeats 11 times. 18:33:14.909 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:14.956 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:14.988 T:1507865504 DEBUG: Previous line repeats 3 times. 18:33:14.988 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:15.096 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:15.733 T:1923711904 DEBUG: Previous line repeats 38 times. 18:33:15.733 T:1923711904 DEBUG: ActiveAE::SyncStream - average error 241.229539 above threshold of 100.000000 18:33:15.758 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:15.810 T:1507865504 DEBUG: Previous line repeats 4 times. 18:33:15.810 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:15.814 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:15.933 T:1923711904 DEBUG: Previous line repeats 5 times. 18:33:15.933 T:1923711904 DEBUG: ActiveAE::SyncStream - average error 0.229539, last average error: 241.229539 18:33:15.981 T:1507865504 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333 18:33:16.082 T:1923711904 DEBUG: ActiveAE::SyncStream - average error of -8.788668, start adjusting 18:33:16.082 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.788668 below threshold of 30.000000 18:33:16.487 T:1507865504 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 18:33:22.155 T:1507865504 WARNING: Previous line repeats 1 times. 18:33:22.155 T:1507865504 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333 18:33:25.998 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:33:25.998 T:1507865504 DEBUG: CPullupCorrection: pattern lost on diff 208541.666667, number of losses 1 18:33:26.888 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:33:26.899 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -337.522323 above threshold of 100.000000 18:33:27.231 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -0.522323 below threshold of 30.000000 18:33:27.940 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:27.942 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:28.404 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:33:28.446 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:29.705 T:1507865504 DEBUG: Previous line repeats 31 times. 18:33:29.705 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:33:29.718 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:29.719 T:1923711904 DEBUG: ActiveAE::SyncStream - average error -1596.640447 above threshold of 100.000000 18:33:30.148 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:30.149 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:30.280 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:30.730 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:33:32.095 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:33:32.882 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:33:34.536 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:33:34.601 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:34.603 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:34.640 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:34.641 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:34.904 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:35.000 T:1507865504 DEBUG: Previous line repeats 1 times. 18:33:35.000 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:35.021 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:35.467 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:33:35.570 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:33:35.570 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:36.088 T:1507865504 DEBUG: Previous line repeats 1 times. 18:33:36.088 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:33:36.733 T:1507865504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 18:33:36.941 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:36.943 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:36.990 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(1) 18:33:36.991 T:1507865504 DEBUG: CMMALVideo::SetDropState - bDrop(0) 18:33:37.437 T:1507865504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 23.976024 fps 18:33:37.478 T:1507865504 DEBUG: OutputPicture - dropped in output 18:33:37.595 T:1788867488 DEBUG: Previous line repeats 3 times. 18:33:37.596 T:1788867488 DEBUG: PushCecKeypress - received key e0 duration 1000 (rep:0 size:0) 18:33:37.596 T:1788867488 DEBUG: PushCecKeypress - added key e0 18:33:37.604 T:1945120768 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 18:33:37.604 T:1945120768 NOTICE: CVideoPlayer::CloseFile() 18:33:37.604 T:1945120768 NOTICE: VideoPlayer: waiting for threads to exit 18:33:37.775 T:1507865504 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 18:33:38.277 T:1549808544 WARNING: Previous line repeats 2 times. 18:33:38.277 T:1549808544 ERROR: ffmpeg[5C6033A0]: [matroska,webm] Read error 18:33:38.277 T:1549808544 DEBUG: CVideoPlayer::SetCaching - caching state 0 18:33:38.277 T:1558197152 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_EOF 18:33:38.277 T:1549808544 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 18:33:38.277 T:1549808544 INFO: Process - eof reading from demuxer 18:33:38.278 T:1549808544 NOTICE: CVideoPlayer::OnExit() 18:33:38.278 T:1549808544 NOTICE: Closing stream player 1 18:33:38.278 T:1549808544 NOTICE: Waiting for audio thread to exit 18:33:38.278 T:1558197152 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 18:33:38.278 T:1558197152 NOTICE: thread end: CVideoPlayerAudio::OnExit() 18:33:38.278 T:1558197152 DEBUG: Thread VideoPlayerAudio 1558197152 terminating 18:33:38.278 T:1549808544 NOTICE: Closing audio device 18:33:38.327 T:1549808544 DEBUG: CDVDAudio::Flush - flush audio stream 18:33:38.328 T:1923711904 DEBUG: CActiveAE::DiscardStream - audio stream deleted 18:33:38.328 T:1923711904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:33:38.330 T:1549808544 DEBUG: Previous line repeats 2 times. 18:33:38.330 T:1549808544 NOTICE: Deleting audio codec 18:33:38.330 T:1549808544 NOTICE: Closing stream player 2 18:33:38.330 T:1549808544 NOTICE: waiting for video thread to exit 18:33:38.331 T:1507865504 NOTICE: thread end: video_thread 18:33:38.331 T:1507865504 DEBUG: Thread VideoPlayerVideo 1507865504 terminating 18:33:38.332 T:1549808544 NOTICE: deleting video codec 18:33:38.335 T:1549808544 NOTICE: Closing stream player 3 18:33:38.336 T:1549808544 DEBUG: CSMBFile::Close closing fd 10000 18:33:38.338 T:1549808544 DEBUG: OnPlayBackStopped: play state was 2, starting 0 18:33:38.338 T:1945120768 NOTICE: VideoPlayer: finished waiting 18:33:38.338 T:1549808544 DEBUG: Thread VideoPlayer 1549808544 terminating 18:33:38.338 T:1945120768 DEBUG: DeleteRenderer - deleting renderer 18:33:38.338 T:1945120768 DEBUG: CMMALRenderer::~CMMALRenderer 18:33:38.338 T:1945120768 DEBUG: CMMALRenderer::UnInitMMAL 18:33:38.355 T:1945117600 DEBUG: GetMovieId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMovie from movie where idFile=13 18:33:38.356 T:1945117600 DEBUG: GetEpisodeId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idEpisode from episode where idFile=13 18:33:38.357 T:1945117600 DEBUG: GetMusicVideoId (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv), query = select idMVideo from musicvideo where idFile=13 18:33:38.361 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 18:33:38.361 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 18:33:38.370 T:1533031328 DEBUG: CMMALRenderer::Process - stopping 18:33:38.370 T:1533031328 DEBUG: Thread MMALRenderer 1533031328 terminating 18:33:38.377 T:1913648032 INFO: CActiveAESink::OpenSink - initialize sink 18:33:38.389 T:1945120768 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x2d6bba0 for port vc.ril.video_decode:out:0(OPQV) 18:33:38.393 T:1755313056 DEBUG: CMMALRenderer::Run - stopping 18:33:38.393 T:1755313056 DEBUG: Thread MMALProcess 1755313056 terminating 18:33:38.394 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:33:38.394 T:1945120768 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 18:33:38.417 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:33:38.433 T:1945120768 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 18:33:38.438 T:1945120768 DEBUG: CGUIMediaWindow::GetDirectory (smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/) 18:33:38.438 T:1945120768 DEBUG: ParentPath = [smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/] 18:33:38.440 T:1945120768 DEBUG: OpenDir: Using authentication url smb://server1/videos/down/Game%20of%20Videos%20S02%20German%20DL%201080p%20BluRay%20x264%20-%20GZCrew 18:33:38.476 T:1913648032 DEBUG: CAESinkPi:Drain delay:99ms now:0ms 18:33:38.476 T:1913648032 DEBUG: CAESinkPi:Deinitialize 18:33:38.477 T:1913648032 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000 18:33:38.485 T:1913648032 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x7170c9b0 18:33:38.486 T:1913648032 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 18:33:38.486 T:1913648032 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI 18:33:38.486 T:1913648032 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 18:33:38.488 T:1913648032 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x717f6030 18:33:38.490 T:1913648032 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignmen(16) 18:33:38.490 T:1913648032 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 18:33:38.490 T:1913648032 DEBUG: Output Device : HDMI 18:33:38.490 T:1913648032 DEBUG: Sample Rate : 44100 18:33:38.490 T:1913648032 DEBUG: Sample Format : AE_FMT_FLOAT 18:33:38.491 T:1913648032 DEBUG: Channel Count : 2 18:33:38.491 T:1913648032 DEBUG: Channel Layout: FL,FR 18:33:38.491 T:1913648032 DEBUG: Frames : 2205 18:33:38.491 T:1913648032 DEBUG: Frame Size : 8 18:33:38.497 T:1923711904 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 18:33:38.522 T:1945120768 DEBUG: Previous line repeats 1 times. 18:33:38.522 T:1945120768 DEBUG: RunQuery took 1 ms for 5 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=6 18:33:38.525 T:1755313056 DEBUG: Thread BackgroundLoader start, auto delete: false 18:33:38.546 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x2d6af80 18:33:38.546 T:1945120768 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(314128), nBufferAlignmen(16) 18:33:38.550 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x2d8c3c0 18:33:38.551 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x2d96e80 18:33:38.551 T:1945120768 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16) 18:33:38.626 T:1755313056 DEBUG: Thread BackgroundLoader 1755313056 terminating 18:33:38.698 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x2d6af80 18:33:38.699 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x2d8c3c0 18:33:38.700 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x2d96e80 18:33:38.700 T:1945120768 DEBUG: DecodeJpegToTexture: decoded special://skin/extras/backgrounds/primary.jpg 1280x720 18:33:38.706 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x2d6af80 18:33:38.707 T:1945120768 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(93824), nBufferAlignmen(16) 18:33:38.709 T:1945120768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x2d8c3c0 18:33:38.709 T:1945120768 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16) 18:33:38.723 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x2d6af80 18:33:38.724 T:1945120768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x2d8c3c0 18:33:38.724 T:1945120768 DEBUG: DecodeJpegToTexture: decoded special://skin/extras/backgrounds/pattern1.jpg 900x506 18:33:38.783 T:1945120768 NOTICE: CVideoPlayer::CloseFile() 18:33:38.783 T:1945120768 NOTICE: VideoPlayer: waiting for threads to exit 18:33:38.783 T:1945120768 NOTICE: VideoPlayer: finished waiting 18:33:38.783 T:1945120768 NOTICE: CVideoPlayer::CloseFile() 18:33:38.783 T:1945120768 NOTICE: VideoPlayer: waiting for threads to exit 18:33:38.783 T:1945120768 NOTICE: VideoPlayer: finished waiting 18:33:38.786 T:1945120768 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 18:33:38.788 T:1755313056 DEBUG: Thread JobWorker start, auto delete: true 18:33:38.788 T:1755313056 DEBUG: DoWork - Saving file state for video item smb://server1/videos/down/Game of Videos S02 German DL 1080p BluRay x264 - GZCrew/Game.of.Videos.S02E05.German.DL.1080p.BluRay.x264-GZCrew.mkv 18:33:38.825 T:1945117600 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 18:33:38.825 T:1945117600 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 18:33:40.217 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 0 (rep:0 size:0) 18:33:40.217 T:1788867488 DEBUG: PushCecKeypress - added key ed 18:33:40.236 T:1945120768 DEBUG: OnKey: 237 (0xed, obc18) pressed, action is PreviousMenu 18:33:40.236 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:33:40.349 T:1788867488 DEBUG: PushCecKeypress - received key ed duration 133 (rep:0 size:0) 18:33:40.350 T:1788867488 DEBUG: PushCecKeypress - ignored key ed 18:33:40.599 T:1945120768 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 18:33:40.601 T:1945120768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:33:40.602 T:1945120768 DEBUG: ------ Window Init (Home.xml) ------ 18:33:40.602 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 18:33:40.602 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 18:33:40.602 T:1516254112 DEBUG: Thread JobWorker start, auto delete: true 18:33:40.603 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 18:33:40.603 T:1541419936 DEBUG: Thread JobWorker start, auto delete: true 18:33:40.603 T:1945120768 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 18:33:42.659 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 0 (rep:0 size:0) 18:33:42.659 T:1788867488 DEBUG: PushCecKeypress - added key a9 18:33:42.695 T:1945120768 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:33:42.829 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 171 (rep:0 size:0) 18:33:42.829 T:1788867488 DEBUG: PushCecKeypress - ignored key a9 18:33:43.138 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 0 (rep:0 size:0) 18:33:43.138 T:1788867488 DEBUG: PushCecKeypress - added key a9 18:33:43.159 T:1945120768 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:33:43.309 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 172 (rep:0 size:0) 18:33:43.309 T:1788867488 DEBUG: PushCecKeypress - ignored key a9 18:33:43.584 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 0 (rep:0 size:0) 18:33:43.584 T:1788867488 DEBUG: PushCecKeypress - added key a9 18:33:43.599 T:1945120768 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 18:33:43.709 T:1788867488 DEBUG: PushCecKeypress - received key a9 duration 125 (rep:0 size:0) 18:33:43.709 T:1788867488 DEBUG: PushCecKeypress - ignored key a9 18:33:44.178 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0) 18:33:44.178 T:1788867488 DEBUG: PushCecKeypress - added key a6 18:33:44.220 T:1945120768 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:33:44.309 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 132 (rep:0 size:0) 18:33:44.309 T:1788867488 DEBUG: PushCecKeypress - ignored key a6 18:33:45.025 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:33:45.026 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:33:45.037 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:33:45.150 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 124 (rep:0 size:0) 18:33:45.150 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:33:45.464 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:33:45.464 T:1788867488 DEBUG: PushCecKeypress - added key b 18:33:45.493 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:33:45.493 T:1945120768 DEBUG: Activating window ID: 10004 18:33:45.590 T:1788867488 DEBUG: PushCecKeypress - received key b duration 126 (rep:0 size:0) 18:33:45.590 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:33:45.815 T:1945120768 DEBUG: ------ Window Deinit (Home.xml) ------ 18:33:45.820 T:1945120768 DEBUG: ------ Window Init (Settings.xml) ------ 18:33:47.312 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:33:47.312 T:1788867488 DEBUG: PushCecKeypress - added key b 18:33:47.328 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:33:47.328 T:1945120768 DEBUG: Activating window ID: 10016 18:33:47.440 T:1788867488 DEBUG: PushCecKeypress - received key b duration 128 (rep:0 size:0) 18:33:47.440 T:1788867488 DEBUG: PushCecKeypress - ignored key b 18:33:47.651 T:1945120768 DEBUG: ------ Window Deinit (Settings.xml) ------ 18:33:47.653 T:1945120768 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 18:33:48.516 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0) 18:33:48.516 T:1788867488 DEBUG: PushCecKeypress - added key a6 18:33:48.550 T:1945120768 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 18:33:48.690 T:1788867488 DEBUG: PushCecKeypress - received key a6 duration 175 (rep:0 size:0) 18:33:48.690 T:1788867488 DEBUG: PushCecKeypress - ignored key a6 18:33:49.742 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 0 (rep:0 size:0) 18:33:49.743 T:1788867488 DEBUG: PushCecKeypress - added key a8 18:33:49.754 T:1945120768 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 18:33:49.960 T:1788867488 DEBUG: PushCecKeypress - received key a8 duration 218 (rep:0 size:0) 18:33:49.960 T:1788867488 DEBUG: PushCecKeypress - ignored key a8 18:33:51.541 T:1788867488 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0) 18:33:51.541 T:1788867488 DEBUG: PushCecKeypress - added key b 18:33:51.554 T:1945120768 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 18:33:51.554 T:1945120768 NOTICE: Disabled debug logging due to GUI setting. Level 0. 18:33:51.554 T:1945120768 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 18:35:12.837 T:1945120768 NOTICE: Samba is idle. Closing the remaining connections
-
argh ... Wieso verpackst du deine Ausgabe nicht in CODE-Blöcke?
FAQ => Nützliche Links / Linksammlung => HowTo: CODE / PHP Code im Forum posten
Bitte Beitrag bearbeiten und anpassen!
-
ganz ehrlich:
Ich würde die SD-Karte einfach neu flashen.* aktuelles Image von LibreElec herunterladen >> Link zum offiziellen LibreELEC-Download (V8.0.2) <<
Nicht irgendein dubioses(?) Zeug mit V8.1.1., das womöglich noch in irgendeinem Betastadium herumhängt.
* Kein NOOBS verwenden!
* Am PC die SD-Karte direkt mit dem heruntergeladenem Image 1:1 flashen:
Unter Windows mit win32diskimager oder am Linux-PC mit dd
* sofort über's Netzwerk ausprobieren, bevor Du irgendwelche zusätzlich gekauften Codecs einspielst! Zunächst nur die allernötigsten Einstellungen vornehmen.* Oder die Links von meigrafd anschauen, da stehen oft sehr nützliche Tipps.
Generell auch darauf achten:
Vor dem Ausschalten den RPi immer sauber herunterfahren! Bei Nichtbeachtung schießt man sich schnell sein Image auf der SD-Karte mit mehr oder weniger Schaden.
Vernünftiges Netzteil verwenden, für den RPi3 am besten das offizielle mit 5,1V, 2,5A -
Zitat von "meigrafd" pid='299553' dateline='1505325477'
argh ... Wieso verpackst du deine Ausgabe nicht in CODE-Blöcke?sehr gute Frage
Zitat von "angelosarikis" pid='299550' dateline='1505325060'
ZWISCHENSTAND:hey du schuldest mir ne neue Maus, mein Scrollrad hat aufgegeben!!!!!
-
habe das mit dem code gemacht. Trotzdem Gegenfrage: warum geht Dateiupload nicht? Dateiformat wird nicht akzeptiert. Warum steht nicht, welches Dateiformat akzeptiert wird?
Jedenfalls werde ich mal neu installieren
Automatisch zusammengefügt:
So. Neu das LibreElec installiert. Exakt dasselbe Problem. Wenn ich das nicht gebacken kriege, dann kriege ich´s von meiner Frau -
Zitat von "angelosarikis" pid='299582' dateline='1505330645'
... Wenn ich das nicht gebacken kriege, dann kriege ich´s von meiner Frau
Das will keiner!
Das Positive daran: Auch Deine Frau hat den RPi kennen- und schätzen gelernt :thumbs1:Wenn es auf dem RPi3 nach dem erneuten Aufspielen immer noch nicht funzt, würde ich mir das aktuelle Image für den RPi1 herunterladen und das auf eine andere SD-Karte aufspielen und den RPi1 damit starten. Auf keinen Fall die funktionierende RPi1-SD-Karte überschreiben! Lieber eine neue kaufen. Hast Du dann auch am RPi1 den gleichen Netzwerkhänger oder funzt der Einser auch mit dem neuen Image einwandfrei?
Wenn der Netzwerkfehler dann auch am RPi1 auftritt, liegt es höchstwahrscheinlich am neuen Build von LibreELEC. Dann kann man den gleichen Entwicklungsstand (altes Image), der auf Deinem funktionierenden RPi1 läuft für RPi2/3 herunterladen und am RPi3 damit testen.
Wenn der Fehler am RPi1 aber nicht auftritt, ist es ein prinzipieller Fehler im verwendeten RPi2/3-Image. Dann würde ich anstelle von LibreELEC mal die Distributionen OSMC oder xbian für RPi3 testen. Damit stellst Du u.a. fest, ob es an kodi selbst liegt oder am spezifischen Linux-Unterbau von LibreELEC.
Wobei ggf. noch zu beachten ist, welche Distro welche kodi-Version benutzt. Da glaube ich, dass LibreELEC im Vergleich die neueste nutzt.schlizbäda
-
Also ich habe jetzt mehrere Distros ausprobiert:
- Openelec
- Libreelec
- OSMCalle 3 jeweils in der aktuellsten Stable Variante => Videos stocken zu sehr.
Xbian "Latest Version" =>geht so einigermaßen. Das Intro stockt ziemlich. Danach kann es schon sein, dass nur der Ton da ist und das Bild schwarz. Nach Stop und Start geht es dann.
Danke jedenfalls schon mal für diesen Tipp!Mich wundert es dann doch etwas, dass das so wackelig ist. Wackelig = ganz schlechter WAF...
Ein 08/15 rpi3 mit Standard Installation sollte doch gehen, oder?
Ich brauche jedenfalls etwas, das rock stable ist (s. WAF).
Gibt es denn so etwas?
Grüße!
-
wenn es mit einer Samsung EVO und am LAN immer noch stockt habe ich keine Idee mehr, aber wlan kannst du eh vergessen
-
Zitat von "jar" pid='299967' dateline='1505501643'
...aber wlan kannst du eh vergessen
welch' wahres Wort!Hattest Du am RPi1 einen USB-WLAN-Stick, der möglicherweise stärker sendet als das integrierte WLAN-Modul des RPi3? Eine schlechte Signalstärke führt zu mehr Übertragungsfehlern. Weil dann die nicht korrekt übertragenen Datenpakete wiederholt werden müssen, geht der Gesamtdatendurchsatz natürlich in die Knie!
Um das zu überprüfen, kannst Du ja mal am RPi3 das interne WLAN-Modul deaktivieren und den USB-WLAN-Stick vom RPi1 in Betrieb nehmen und verwenden. Was passiert dann?Aber wie jar schon sagte: WLAN (vor allem mit schlechter Signalstärke) taugt nichts!
-
LÖSUNG:
Das Smb-Netzwerk war einfach zu langsam. Ich bin auf NFS umgestiegen und nun geht es!
Danke nochmal!
-
aber bei 100Mbit im LAN ist smb nicht zu langsam? sehr merkwürdig.
-
- Offizieller Beitrag
-
-
Jetzt mitmachen!
Du hast noch kein Benutzerkonto auf unserer Seite? Registriere dich kostenlos und nimm an unserer Community teil!