2015-01-19, 15:56
I probably stuffed up the settings, I've not a lot of experience with handbrake unfortunately. 
2015-02-07, 16:49
Little update: The issue is still present in Openelec 5.0.1 on the new Pi2.
2015-02-10, 12:00
Just noticed this thread. I'm also having the same problems with downloaded torrents from the web. It's definately has to do with bad timestamps that omxplayer/dvdplayer can't handle correctly. Enabling interlacing simply masks the problem for a while but the decoder doesn't know how to handle these bad timestamps.
I have also noticed this problem with xvid material, either 720p or 1080p from the times of frodo. While gotham/dvdplayer were resolving it quite good, it re-appeared for those problematic files in helix.
The only workaround for me so far is to rewind and play again...
I have also noticed this problem with xvid material, either 720p or 1080p from the times of frodo. While gotham/dvdplayer were resolving it quite good, it re-appeared for those problematic files in helix.
The only workaround for me so far is to rewind and play again...
2015-02-10, 13:32
(2015-02-10, 12:00)vprasinos Wrote: I have also noticed this problem with xvid material, either 720p or 1080p from the times of frodo. While gotham/dvdplayer were resolving it quite good, it re-appeared for those problematic files in helix.If you have a sample file and can point to a build that behaves better than current helix build then I can look into it.
2015-02-10, 14:17
(2015-02-10, 13:32)popcornmix Wrote:(2015-02-10, 12:00)vprasinos Wrote: I have also noticed this problem with xvid material, either 720p or 1080p from the times of frodo. While gotham/dvdplayer were resolving it quite good, it re-appeared for those problematic files in helix.If you have a sample file and can point to a build that behaves better than current helix build then I can look into it.
Openelec 3.2.4, which used XBMC 12.2 did not show this behaviour.
2015-02-10, 14:48
2015-02-23, 00:35
Hi,
I tested several versions from the openelec archive, and it seems that this bug was introduced with Openelec 3.95.6.
All the version prior to this do not stutter.
So, what changed there? And can it be undone?
I tested several versions from the openelec archive, and it seems that this bug was introduced with Openelec 3.95.6.
All the version prior to this do not stutter.
So, what changed there? And can it be undone?
2015-02-23, 17:33
(2015-02-23, 00:35)Ntor Wrote: I tested several versions from the openelec archive, and it seems that this bug was introduced with Openelec 3.95.6.
All the version prior to this do not stutter.
So, what changed there? And can it be undone?
Assuming you are saying that 3.95.5 is good, then that narrows it down to a change between April 7 and April 15 2014, which is helpful.
There was one firmware commit within that window related to timestamp handling.
I'll see if I can revert that part of the firmware back and see if your sample behaves better. If it does, it should be possible to work out what the difference is.
2015-02-23, 17:55
Try adding to config.txt
It may break other files, but should make these files behave as they used to.
I need to find a way of determining this automatically...
Code:
avoid_fix_ts=2It may break other files, but should make these files behave as they used to.
I need to find a way of determining this automatically...
2015-02-23, 23:56
I've pushed a firmware fix for this issue.
You can test by downloading:
https://github.com/raspberrypi/firmware/...tart_x.elf
https://github.com/raspberrypi/firmware/...ixup_x.dat
and replace start.elf and fixup.dat (renaming from start_x.elf and fixup_x.dat) on the boot partition of sdcard.
Or try with next (probably not tonight's) Milhouse build.
You can test by downloading:
https://github.com/raspberrypi/firmware/...tart_x.elf
https://github.com/raspberrypi/firmware/...ixup_x.dat
and replace start.elf and fixup.dat (renaming from start_x.elf and fixup_x.dat) on the boot partition of sdcard.
Or try with next (probably not tonight's) Milhouse build.
2015-02-24, 21:11
(2015-01-18, 03:14)Ntor Wrote: Hi,i have observed a very very similar issue, but maybe in an other context.
I use openelec (now version 5) on a Raspberry Pi. I always encode my blurays using x264 to an external hard drive which is connected to the raspberry.
Now, sometimes the video stutters for a bit. Not only with 1080p, but also 720p. Most of the time it seems to appear in dark scenes. Most of the time, it goes away after a scene changes. The funny thing is, if I go back 30 seconds and replay the same scene, everything is smooth, no stuttering at all, which seems to implicate some kind of buffering problem?
Just now I encountered this problem, rewinded and then the stuttering was gone. I stoped the playback, started something else, and then went back to the same file, and the stuttering startet again. It is always at the same point in the video, which seems to implicate wrong encoding settings? (which would be bad... I dont want to encode everything again). When I want to play the same file back with vlc on my pc, everything is smooth as it should be.
And the last thing, I could swear that in an older version of openelec ... something like 3.2, this issue was not present.
Any Ideas?
Thx.
(stuttering playback; smooth playback fo same part after rewind; ... vlc on pc plays same video smoothly...
but on my it is not related only on dark scenes or at frame changes - it is more the kind of movement of background and foreground...
anyho... every thing els is exact the same behavior)
i used Handbrake to convert to MKV/MP4 and all content i converted with handbrake version 0.9.9 gives a smooth playback and all content i converted with handbrake version 0.10.0 hast partitally stutter in video playback.
(see issue report here 0.10.0 vs. 0.9.9 sometimes non-smooth playback on RaspberryPi)
here is the kodi log of playing those two videos...
(the source video was exact the same, one was converted with v0.9.9 and the other with 0.10.0 both with exact the same build-in presets - high profile)
Code:
################################################################################
# ... output of cat /storage/.kodi/temp/kodi.log
# OpenELEC release: RPi.arm-5.0.3
#
################################################################################
19:38:23 T:1969545216 NOTICE: special://profile/ is mapped to: special://masterprofile/
19:38:23 T:1969545216 NOTICE: -----------------------------------------------------------------------
19:38:23 T:1969545216 NOTICE: Starting Kodi (14.1 Git:b5dbdb5). Platform: Linux ARM 32-bit
19:38:23 T:1969545216 NOTICE: Using Release Kodi x32 build (version for Raspberry Pi)
19:38:23 T:1969545216 NOTICE: Kodi compiled Feb 10 2015 by GCC 4.9.2 for Linux ARM 32-bit version 3.17.8 (200968)
19:38:23 T:1969545216 NOTICE: Running on OpenELEC (official) - Version: 5.0.3, kernel: Linux ARM 32-bit version 3.17.8
19:38:23 T:1969545216 NOTICE: FFmpeg version: 2.4.6
19:38:23 T:1969545216 NOTICE: WARNING: unsupported ffmpeg version detected
19:38:23 T:1969545216 NOTICE: Host CPU: ARMv6-compatible processor rev 7 (v6l), 1 core available
19:38:23 T:1969545216 NOTICE: ARM Features: Neon disabled
19:38:23 T:1969545216 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
19:38:23 T:1969545216 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
19:38:23 T:1969545216 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata
19:38:23 T:1969545216 NOTICE: special://home/ is mapped to: /storage/.kodi
19:38:23 T:1969545216 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp
19:38:23 T:1969545216 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
19:38:23 T:1969545216 NOTICE: Local hostname: uSD16-1-OpenELEC
19:38:23 T:1969545216 NOTICE: Log File is located: /storage/.kodi/temp/kodi.log
19:38:23 T:1969545216 NOTICE: -----------------------------------------------------------------------
19:38:23 T:1969545216 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
19:38:23 T:1969545216 NOTICE: load settings...
19:38:23 T:1969529920 NOTICE: Thread FDEventMonitor start, auto delete: false
19:38:23 T:1969545216 NOTICE: Found 1 Lists of Devices
19:38:23 T:1969545216 NOTICE: Enumerated PI devices:
19:38:23 T:1969545216 NOTICE: Device 1
19:38:23 T:1969545216 NOTICE: m_deviceName : HDMI
19:38:23 T:1969545216 NOTICE: m_displayName : HDMI
19:38:23 T:1969545216 NOTICE: m_displayNameExtra:
19:38:23 T:1969545216 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:38:23 T:1969545216 NOTICE: m_channels : FL,FR
19:38:23 T:1969545216 NOTICE: m_sampleRates : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
19:38:23 T:1969545216 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_AC3,AE_FMT_DTS,AE_FMT_EAC3
19:38:23 T:1969545216 NOTICE: Device 2
19:38:23 T:1969545216 NOTICE: m_deviceName : Analogue
19:38:23 T:1969545216 NOTICE: m_displayName : Analogue
19:38:23 T:1969545216 NOTICE: m_displayNameExtra:
19:38:23 T:1969545216 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:38:23 T:1969545216 NOTICE: m_channels : FL,FR
19:38:23 T:1969545216 NOTICE: m_sampleRates : 48000
19:38:23 T:1969545216 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:38:23 T:1969545216 NOTICE: Device 3
19:38:23 T:1969545216 NOTICE: m_deviceName : Both
19:38:23 T:1969545216 NOTICE: m_displayName : HDMI and Analogue
19:38:23 T:1969545216 NOTICE: m_displayNameExtra:
19:38:23 T:1969545216 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:38:23 T:1969545216 NOTICE: m_channels : FL,FR
19:38:23 T:1969545216 NOTICE: m_sampleRates : 48000
19:38:23 T:1969545216 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:38:23 T:1969545216 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
19:38:23 T:1969545216 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
<?xml version="1.0" encoding="UTF-8" ?>
<advancedsettings>
<showexitbutton>false</showexitbutton>
<fanartres>720</fanartres>
<imageres>540</imageres>
<video>
<busydialogdelayms>750</busydialogdelayms>
</video>
<samba>
<clienttimeout>30</clienttimeout>
</samba>
<network>
<readbufferfactor>4.0</readbufferfactor>
</network>
</advancedsettings>
19:38:23 T:1969545216 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
19:38:23 T:1969545216 NOTICE: Default DVD Player: dvdplayer
19:38:23 T:1969545216 NOTICE: Default Video Player: dvdplayer
19:38:23 T:1969545216 NOTICE: Default Audio Player: paplayer
19:38:23 T:1969545216 NOTICE: Enabled debug logging due to GUI setting (2)
19:38:23 T:1969545216 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
19:38:23 T:1969545216 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
19:38:23 T:1969545216 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
19:38:23 T:1969545216 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
19:38:23 T:1969545216 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
19:38:23 T:1969545216 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
19:38:23 T:1969545216 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
19:38:23 T:1969545216 NOTICE: Loaded playercorefactory configuration
...
19:38:45 T:1805644864 DEBUG: CPVRTimers - Update - updating timers
19:38:45 T:1969545216 DEBUG: CGUIMediaWindow::GetDirectory ()
19:38:45 T:1969545216 DEBUG: ParentPath = []
19:38:45 T:1684239424 NOTICE: Thread BackgroundLoader start, auto delete: false
19:38:45 T:1684239424 DEBUG: Thread BackgroundLoader 1684239424 terminating
19:38:45 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:45 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:46 T:1969545216 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------
19:38:49 T:1969545216 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0
19:38:49 T:1969545216 DEBUG: OnKey: up (0xf080) pressed, action is Up
19:38:50 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:50 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:50 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:50 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:52 T:1969545216 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
19:38:52 T:1969545216 DEBUG: OnKey: return (0xf00d) pressed, action is Select
19:38:52 T:1969545216 DEBUG: CGUIMediaWindow::GetDirectory (library://video/files.xml/)
19:38:52 T:1969545216 DEBUG: ParentPath = []
19:38:52 T:1684239424 NOTICE: Thread BackgroundLoader start, auto delete: false
19:38:52 T:1684239424 DEBUG: Thread BackgroundLoader 1684239424 terminating
19:38:53 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:53 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:54 T:1969545216 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
19:38:54 T:1969545216 DEBUG: OnKey: return (0xf00d) pressed, action is Select
19:38:54 T:1969545216 DEBUG: CGUIMediaWindow::GetDirectory (smb://NAS-01/share/Videos/EQ/)
19:38:54 T:1969545216 DEBUG: ParentPath = [sources://video/]
19:38:55 T:1969545216 DEBUG: RunQuery took 9 ms for 0 items query: select * from movieview WHERE c23=1
19:38:55 T:1684239424 NOTICE: Thread BackgroundLoader start, auto delete: false
19:38:55 T:1969545216 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-arm.so)
19:38:55 T:1969545216 DEBUG: Unloading: /ImageLib-arm.so
19:38:55 T:1969545216 NOTICE: UDev: Added /var/media/MICROSD
19:38:55 T:1969545216 DEBUG: ------ Window Init (DialogKaiToast.xml) ------
19:38:56 T:1684239424 DEBUG: Thread BackgroundLoader 1684239424 terminating
19:38:56 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:56 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:56 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:56 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:56 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:56 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:57 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:57 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:57 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:57 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:38:58 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:38:58 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:39:01 T:1969545216 INFO: CheckIdle - Closing session to http://file.xbmcmxtv.com (easy=0x74d43c28, multi=0x74d55478)
19:39:01 T:1969545216 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
19:39:02 T:1969545216 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
19:39:02 T:1969545216 DEBUG: OnKey: return (0xf00d) pressed, action is Select
19:39:02 T:1969545216 DEBUG: CGUIMediaWindow::GetDirectory (smb://NAS-01/share/Videos/EQ/test/)
19:39:02 T:1969545216 DEBUG: ParentPath = [smb://NAS-01/share/Videos/EQ/]
19:39:03 T:1969545216 DEBUG: RunQuery took 6 ms for 7 items query: select * from movieview WHERE c23=20
19:39:03 T:1684239424 NOTICE: Thread BackgroundLoader start, auto delete: false
19:39:03 T:1684239424 DEBUG: Thread BackgroundLoader 1684239424 terminating
19:39:06 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:39:06 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:39:06 T:1969545216 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
19:39:06 T:1969545216 DEBUG: OnKey: return (0xf00d) pressed, action is Select
19:39:06 T:1969545216 DEBUG: CGUIMediaWindow::GetDirectory (smb://NAS-01/share/Videos/EQ/test/issue/)
19:39:06 T:1969545216 DEBUG: ParentPath = [smb://NAS-01/share/Videos/EQ/test/]
19:39:06 T:1969545216 DEBUG: RunQuery took 3 ms for 0 items query: select * from movieview WHERE c23=21
19:39:06 T:1969545216 DEBUG: RunQuery took 2 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=21
19:39:06 T:1684239424 NOTICE: Thread BackgroundLoader start, auto delete: false
19:39:07 T:1684239424 DEBUG: Thread BackgroundLoader 1684239424 terminating
19:39:08 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:39:08 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:39:08 T:1969545216 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:39:08 T:1969545216 DEBUG: OnKey: down (0xf081) pressed, action is Down
19:39:09 T:1969545216 DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
19:39:09 T:1969545216 DEBUG: OnKey: return (0xf00d) pressed, action is Select
19:39:10 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idMovie from movie where idFile=430
19:39:10 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idEpisode from episode where idFile=430
19:39:10 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idMVideo from musicvideo where idFile=430
19:39:10 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:39:10 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:39:10 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idMovie from movie where idFile=429
19:39:10 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idEpisode from episode where idFile=429
19:39:10 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idMVideo from musicvideo where idFile=429
19:39:10 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:39:10 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:39:10 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:39:10 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:39:10 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/title00.mkv), query = select idMovie from movie where idFile=439
19:39:10 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/title00.mkv), query = select idEpisode from episode where idFile=439
19:39:10 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/title00.mkv), query = select idMVideo from musicvideo where idFile=439
19:39:10 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:39:10 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:39:10 T:1969545216 DEBUG: Loading settings for smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv
19:39:10 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers(smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv)
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:39:10 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
19:39:10 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:39:10 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:39:10 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:39:10 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:39:10 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:39:10 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:39:10 T:1949299776 DEBUG: CAESinkPi:Drain delay:99ms now:0ms
19:39:10 T:1949299776 DEBUG: CAESinkPi:Deinitialize
19:39:10 T:1949299776 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
19:39:10 T:1949299776 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x18ef490
19:39:10 T:1969545216 NOTICE: DVDPlayer: Opening: smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv
19:39:10 T:1969545216 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:39:10 T:1969545216 DEBUG: CMMALRenderer::CMMALRenderer
19:39:10 T:1969545216 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:39:10 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:39:10 T:1969545216 DEBUG: CMMALRenderer::PreInit
19:39:10 T:1669153856 NOTICE: Thread DVDPlayer start, auto delete: false
19:39:10 T:1669153856 NOTICE: Creating InputStream
19:39:10 T:1684239424 NOTICE: Thread CMMALRenderer start, auto delete: false
19:39:10 T:1669153856 DEBUG: CSMBFile::Open - opened share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv, fd=10000
19:39:10 T:1669153856 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
19:39:10 T:1669153856 DEBUG: ScanForExternalSubtitles: END (total time: 34 ms)
19:39:10 T:1669153856 NOTICE: Creating Demuxer
19:39:10 T:1669153856 DEBUG: Open - probing detected format [matroska,webm]
19:39:10 T:1669153856 DEBUG: Open - avformat_find_stream_info starting
19:39:10 T:1669153856 DEBUG: Open - av_find_stream_info finished
19:39:10 T:1669153856 INFO: ffmpeg[637D4440]: Input #0, matroska,webm, from 'smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv':
19:39:10 T:1669153856 INFO: ffmpeg[637D4440]: Metadata:
19:39:10 T:1669153856 INFO: ffmpeg[637D4440]: encoder : libmkv 0.6.5
19:39:10 T:1669153856 INFO: ffmpeg[637D4440]: Duration: 00:07:07.04, start: 0.000000, bitrate: 17914 kb/s
19:39:10 T:1669153856 INFO: ffmpeg[637D4440]: Stream #0:0(eng): Video: h264 (High), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], 24 fps, 24 tbr, 1k tbn, 180k tbc (default)
19:39:10 T:1669153856 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
19:39:10 T:1669153856 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81 m_handle 0x74def8f8
19:39:10 T:1669153856 DEBUG: OMXClock::OMXStop
19:39:10 T:1669153856 DEBUG: OMXClock::OMXSetSpeed(0.00) pause_resume:1
19:39:10 T:1669153856 NOTICE: Opening stream: 0 source: 256
19:39:10 T:1669153856 DEBUG: DllBcm: Using omx system library
19:39:10 T:1669153856 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131 m_handle 0x74d40798
19:39:10 T:1669153856 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(120), nBufferSize(81920), nBufferAlignmen(16)
19:39:10 T:1669153856 DEBUG: COMXVideo::Open - decoder_component(0x0x74d40798), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 1
19:39:10 T:1669153856 INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-h264 width 1920 height 1080 profile 100 fps 24.000000
19:39:10 T:1669153856 NOTICE: Creating video thread
19:39:10 T:1669153856 ERROR: Get - failed to get stream
19:39:10 T:1669153856 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv
19:39:10 T:1669153856 DEBUG: OnPlayBackStarted: play state was 1, starting 1
19:39:10 T:1969545216 DEBUG: PlayFile: OpenFile succeed, play state 2
19:39:10 T:1969545216 DEBUG: OnPlayBackStarted: play state was 2, starting 0
19:39:10 T:1669153856 DEBUG: CDVDPlayer::SetCaching - caching state 3
19:39:10 T:1669153856 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
19:39:10 T:1652552768 NOTICE: Thread OMXPlayerVideo start, auto delete: false
19:39:10 T:1652552768 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
19:39:10 T:1652552768 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0
19:39:10 T:1652552768 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
19:39:10 T:1652552768 INFO: COMXPlayerVideo - Stillframe left, switching to normal playback
19:39:10 T:1652552768 DEBUG: OMXVideo::Decode VDec : setStartTime 0.000000
19:39:10 T:1652552768 INFO: Output - renderer not started
19:39:10 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x1efc1d8
19:39:10 T:1969545216 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(81920), nBufferAlignmen(16)
19:39:10 T:1669153856 DEBUG: CDVDPlayer::HandleMessages - player started 2
19:39:10 T:1669153856 DEBUG: CDVDPlayer::HandleMessages player started RESET
19:39:10 T:1669153856 NOTICE: OMXClock using video as reference
19:39:10 T:1669153856 DEBUG: OMXClock::OMXReset audio / video : 0 / 1 wait mask 0->2 state : 2->1
19:39:10 T:1669153856 DEBUG: CDVDPlayer::HandleMessages player started 2 (s:1000 a:0 v:1)
19:39:10 T:1669153856 DEBUG: CDVDPlayer::SetCaching - caching state 0
19:39:10 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x1b7b5a8
19:39:10 T:1652552768 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000
19:39:10 T:1652552768 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 167000.000000, 1)
19:39:10 T:1652552768 INFO: Output - renderer not started
19:39:10 T:1969545216 INFO: Previous line repeats 3 times.
19:39:10 T:1969545216 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16)
19:39:10 T:1652552768 INFO: Output - renderer not started
19:39:11 T:1669153856 DEBUG: CDVDPlayer::OMXDoProcessing Resume -4503599616.00,0.25 (A:01 V:01) EOF:0 FULL:0 T:0.20
19:39:11 T:1669153856 DEBUG: OMXClock::OMXSetSpeed(1.00) pause_resume:1
19:39:11 T:1652552768 INFO: Output - renderer not started
19:39:11 T:1969545216 INFO: Previous line repeats 2 times.
19:39:11 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x1efc1d8
19:39:11 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x1b7b5a8
19:39:11 T:1969545216 DEBUG: DecodeJpegToTexture: decoded special://skin/backgrounds/media-overlay.jpg 1280x720
19:39:11 T:1652552768 INFO: Output - renderer not started
19:39:11 T:1652552768 INFO: Previous line repeats 3 times.
19:39:11 T:1652552768 DEBUG: COMXVideo::PortSettingsChanged - [email protected] interlace:0 deinterlace:0
19:39:11 T:1652552768 DEBUG: ResolutionUpdateCallBack - change configuration. video:1920x1080. framerate: 24.00. 1920x1080 format: BYPASS
19:39:11 T:1652552768 DEBUG: CMMALRenderer::Configure - 1920x1080->[email protected] flags:10 format:14 ext:0 orient:0
19:39:11 T:1652552768 NOTICE: Display resolution ADJUST : 1920x1080 (1920x1080) @ 24.00 - Full Screen (27) (weight: 0.000)
19:39:11 T:1652552768 DEBUG: CXBMCRenderManager::Configure - 3
19:39:11 T:1652552768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90 m_handle 0x68de4eb0
19:39:11 T:1652552768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11 m_handle 0x697ef5c8
19:39:11 T:1969545216 DEBUG: OMXPlayerVideo::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 (0,0,0,0,mono)
19:39:11 T:1969545216 DEBUG: COMXVideo::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 mode:1
19:39:11 T:1969545216 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv)
19:39:11 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idMovie from movie where idFile=430
19:39:11 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idEpisode from episode where idFile=430
19:39:11 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idMVideo from musicvideo where idFile=430
19:39:11 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:39:11 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:39:11 T:1969545216 DEBUG: Activating window ID: 12005
19:39:11 T:1969545216 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:39:11 T:1969545216 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:39:11 T:1969545216 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
19:39:36 T:1829377088 DEBUG: Thread JobWorker 1829377088 terminating (autodelete)
19:40:02 T:1873802304 DEBUG: PushCecKeypress - received key e0 duration 1086
19:40:02 T:1969545216 DEBUG: OnKey: 224 (0xe0) pressed, action is Stop
19:40:02 T:1969545216 NOTICE: CDVDPlayer::CloseFile()
19:40:02 T:1969545216 NOTICE: DVDPlayer: waiting for threads to exit
19:40:02 T:1669153856 ERROR: ffmpeg[637D4440]: [matroska,webm] Read error
19:40:02 T:1669153856 NOTICE: CDVDPlayer::OnExit()
19:40:02 T:1669153856 NOTICE: Closing stream player 2
19:40:02 T:1652552768 NOTICE: thread end: video_thread
19:40:02 T:1652552768 DEBUG: Thread OMXPlayerVideo 1652552768 terminating
19:40:03 T:1669153856 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_scheduler handle 0x697ef5c8
19:40:03 T:1669153856 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_decode handle 0x74d40798
19:40:03 T:1669153856 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_render handle 0x68de4eb0
19:40:03 T:1669153856 DEBUG: CSMBFile::Close closing fd 10000
19:40:03 T:1669153856 DEBUG: OMXClock::OMXStop
19:40:03 T:1669153856 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.clock handle 0x74def8f8
19:40:03 T:1669153856 DEBUG: OnPlayBackStopped: play state was 2, starting 0
19:40:03 T:1669153856 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
19:40:03 T:1669153856 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
19:40:03 T:1969545216 NOTICE: DVDPlayer: finished waiting
19:40:03 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:40:03 T:1969545216 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:40:03 T:1969545216 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:40:03 T:1669153856 DEBUG: Thread DVDPlayer 1669153856 terminating
19:40:03 T:1969545216 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:40:03 T:1969545216 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:40:03 T:1969545216 DEBUG: CGUIMediaWindow::GetDirectory (smb://NAS-01/share/Videos/EQ/test/issue/)
19:40:03 T:1969545216 DEBUG: ParentPath = [smb://NAS-01/share/Videos/EQ/test/issue/]
19:40:03 T:1969545216 DEBUG: RunQuery took 4 ms for 0 items query: select * from movieview WHERE c23=21
19:40:03 T:1969545216 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=21
19:40:03 T:1669153856 NOTICE: Thread BackgroundLoader start, auto delete: false
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x25be198
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(125120), nBufferAlignmen(16)
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x291d3c0
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x292ae18
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16)
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x25be198
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x291d3c0
19:40:03 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x292ae18
19:40:03 T:1969545216 DEBUG: DecodeJpegToTexture: decoded special://skin/backgrounds/SKINDEFAULT.jpg 1280x720
19:40:03 T:1669153856 DEBUG: Thread BackgroundLoader 1669153856 terminating
19:40:03 T:1969545216 NOTICE: CDVDPlayer::CloseFile()
19:40:03 T:1969545216 NOTICE: DVDPlayer: waiting for threads to exit
19:40:03 T:1969545216 NOTICE: DVDPlayer: finished waiting
19:40:03 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:40:03 T:1969545216 NOTICE: CDVDPlayer::CloseFile()
19:40:03 T:1969545216 NOTICE: DVDPlayer: waiting for threads to exit
19:40:03 T:1969545216 NOTICE: DVDPlayer: finished waiting
19:40:03 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:40:03 T:1959785536 INFO: CAESinkALSA - Unable to open device "default" for playback
19:40:03 T:1959785536 NOTICE: Found 1 Lists of Devices
19:40:03 T:1959785536 NOTICE: Enumerated PI devices:
19:40:03 T:1959785536 NOTICE: Device 1
19:40:03 T:1959785536 NOTICE: m_deviceName : HDMI
19:40:03 T:1959785536 NOTICE: m_displayName : HDMI
19:40:03 T:1959785536 NOTICE: m_displayNameExtra:
19:40:03 T:1959785536 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:40:03 T:1959785536 NOTICE: m_channels : FL,FR
19:40:03 T:1959785536 NOTICE: m_sampleRates : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
19:40:03 T:1959785536 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_AC3,AE_FMT_DTS,AE_FMT_EAC3
19:40:03 T:1959785536 NOTICE: Device 2
19:40:03 T:1959785536 NOTICE: m_deviceName : Analogue
19:40:03 T:1959785536 NOTICE: m_displayName : Analogue
19:40:03 T:1959785536 NOTICE: m_displayNameExtra:
19:40:03 T:1959785536 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:40:03 T:1959785536 NOTICE: m_channels : FL,FR
19:40:03 T:1959785536 NOTICE: m_sampleRates : 48000
19:40:03 T:1959785536 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:40:03 T:1959785536 NOTICE: Device 3
19:40:03 T:1959785536 NOTICE: m_deviceName : Both
19:40:03 T:1959785536 NOTICE: m_displayName : HDMI and Analogue
19:40:03 T:1959785536 NOTICE: m_displayNameExtra:
19:40:03 T:1959785536 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:40:03 T:1959785536 NOTICE: m_channels : FL,FR
19:40:03 T:1959785536 NOTICE: m_sampleRates : 48000
19:40:03 T:1959785536 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:40:03 T:1949299776 INFO: CActiveAESink::OpenSink - initialize sink
19:40:03 T:1949299776 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
19:40:03 T:1949299776 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
19:40:03 T:1949299776 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
19:40:03 T:1949299776 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x2934fd0
19:40:03 T:1949299776 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignmen(16)
19:40:03 T:1949299776 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
19:40:03 T:1949299776 DEBUG: Output Device : HDMI
19:40:03 T:1949299776 DEBUG: Sample Rate : 44100
19:40:03 T:1949299776 DEBUG: Sample Format : AE_FMT_FLOAT
19:40:03 T:1949299776 DEBUG: Channel Count : 2
19:40:03 T:1949299776 DEBUG: Channel Layout: FL,FR
19:40:03 T:1949299776 DEBUG: Frames : 2205
19:40:03 T:1949299776 DEBUG: Frame Samples : 4410
19:40:03 T:1949299776 DEBUG: Frame Size : 8
19:40:03 T:1959785536 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:40:03 T:1669153856 NOTICE: Thread JobWorker start, auto delete: true
19:40:03 T:1669153856 DEBUG: DoWork - Saving file state for video item smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv
19:40:06 T:1873802304 DEBUG: PushCecKeypress - received key a7 duration 0
19:40:06 T:1969545216 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
19:40:07 T:1873802304 DEBUG: PushCecKeypress - received key a7 duration 137
19:40:09 T:1873802304 DEBUG: PushCecKeypress - received key b duration 0
19:40:09 T:1969545216 DEBUG: OnKey: 11 (0x0b) pressed, action is Select
19:40:09 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
19:40:09 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
19:40:09 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idMovie from movie where idFile=430
19:40:09 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idEpisode from episode where idFile=430
19:40:09 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_9_9-2.mkv), query = select idMVideo from musicvideo where idFile=430
19:40:09 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:40:09 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:40:09 T:1873802304 DEBUG: PushCecKeypress - received key b duration 174
19:40:09 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idMovie from movie where idFile=429
19:40:09 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idEpisode from episode where idFile=429
19:40:09 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idMVideo from musicvideo where idFile=429
19:40:09 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:40:09 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:40:09 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:40:09 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:40:09 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/title00.mkv), query = select idMovie from movie where idFile=439
19:40:09 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/title00.mkv), query = select idEpisode from episode where idFile=439
19:40:09 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/title00.mkv), query = select idMVideo from musicvideo where idFile=439
19:40:09 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:40:09 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:40:09 T:1969545216 DEBUG: Loading settings for smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv
19:40:09 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers(smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv)
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:40:09 T:1969545216 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
19:40:09 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:40:09 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:40:09 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:40:09 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:40:09 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:40:09 T:1969545216 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:40:09 T:1949299776 DEBUG: CAESinkPi:Drain delay:99ms now:0ms
19:40:09 T:1949299776 DEBUG: CAESinkPi:Deinitialize
19:40:09 T:1949299776 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
19:40:09 T:1949299776 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x2934fd0
19:40:09 T:1969545216 NOTICE: DVDPlayer: Opening: smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv
19:40:09 T:1969545216 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:40:09 T:1969545216 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:40:09 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:40:09 T:1969545216 DEBUG: CMMALRenderer::PreInit
19:40:09 T:1652552768 NOTICE: Thread DVDPlayer start, auto delete: false
19:40:09 T:1652552768 NOTICE: Creating InputStream
19:40:09 T:1652552768 DEBUG: CSMBFile::Open - opened share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv, fd=10000
19:40:09 T:1652552768 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
19:40:09 T:1652552768 DEBUG: ScanForExternalSubtitles: END (total time: 34 ms)
19:40:09 T:1652552768 NOTICE: Creating Demuxer
19:40:09 T:1652552768 DEBUG: Open - probing detected format [matroska,webm]
19:40:09 T:1652552768 DEBUG: Open - avformat_find_stream_info starting
19:40:10 T:1652552768 DEBUG: Open - av_find_stream_info finished
19:40:10 T:1652552768 INFO: ffmpeg[627FF440]: Input #0, matroska,webm, from 'smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv':
19:40:10 T:1652552768 INFO: ffmpeg[627FF440]: Metadata:
19:40:10 T:1652552768 INFO: ffmpeg[627FF440]: ENCODER : Lavf55.12.0
19:40:10 T:1652552768 INFO: ffmpeg[627FF440]: Duration: 00:07:07.13, start: 0.083000, bitrate: 17910 kb/s
19:40:10 T:1652552768 INFO: ffmpeg[627FF440]: Stream #0:0: Video: h264 (High), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], 24 fps, 24 tbr, 1k tbn, 180k tbc (default)
19:40:10 T:1652552768 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
19:40:10 T:1652552768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81 m_handle 0x697fb550
19:40:10 T:1652552768 DEBUG: OMXClock::OMXStop
19:40:10 T:1652552768 DEBUG: OMXClock::OMXSetSpeed(0.00) pause_resume:1
19:40:10 T:1652552768 NOTICE: Opening stream: 0 source: 256
19:40:10 T:1652552768 DEBUG: DllBcm: Using omx system library
19:40:10 T:1652552768 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131 m_handle 0x68ddfd78
19:40:10 T:1652552768 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(120), nBufferSize(81920), nBufferAlignmen(16)
19:40:10 T:1652552768 DEBUG: COMXVideo::Open - decoder_component(0x0x68ddfd78), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 1
19:40:10 T:1652552768 INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-h264 width 1920 height 1080 profile 100 fps 24.000000
19:40:10 T:1652552768 NOTICE: Creating video thread
19:40:10 T:1652552768 ERROR: Get - failed to get stream
19:40:10 T:1652552768 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv
19:40:10 T:1652552768 DEBUG: OnPlayBackStarted: play state was 1, starting 1
19:40:10 T:1969545216 DEBUG: PlayFile: OpenFile succeed, play state 2
19:40:10 T:1969545216 DEBUG: OnPlayBackStarted: play state was 2, starting 0
19:40:10 T:1829377088 NOTICE: Thread OMXPlayerVideo start, auto delete: false
19:40:10 T:1829377088 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
19:40:10 T:1652552768 DEBUG: CDVDPlayer::SetCaching - caching state 3
19:40:10 T:1652552768 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
19:40:10 T:1829377088 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0
19:40:10 T:1829377088 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
19:40:10 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x2916168
19:40:10 T:1969545216 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(81920), nBufferAlignmen(16)
19:40:10 T:1829377088 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 0.000000, 1)
19:40:10 T:1829377088 INFO: COMXPlayerVideo - Stillframe left, switching to normal playback
19:40:10 T:1829377088 DEBUG: OMXVideo::Decode VDec : setStartTime 0.000000
19:40:10 T:1829377088 INFO: Output - renderer not started
19:40:10 T:1652552768 DEBUG: CDVDPlayer::HandleMessages - player started 2
19:40:10 T:1652552768 DEBUG: CDVDPlayer::HandleMessages player started RESET
19:40:10 T:1652552768 NOTICE: OMXClock using video as reference
19:40:10 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x2073948
19:40:10 T:1969545216 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16)
19:40:10 T:1652552768 DEBUG: OMXClock::OMXReset audio / video : 0 / 1 wait mask 0->2 state : 2->1
19:40:10 T:1652552768 DEBUG: CDVDPlayer::HandleMessages player started 2 (s:1000 a:0 v:1)
19:40:10 T:1652552768 DEBUG: CDVDPlayer::SetCaching - caching state 0
19:40:10 T:1829377088 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000
19:40:10 T:1829377088 INFO: Output - renderer not started
19:40:10 T:1829377088 INFO: Previous line repeats 5 times.
19:40:10 T:1829377088 DEBUG: COMXVideo::PortSettingsChanged - [email protected] interlace:0 deinterlace:0
19:40:10 T:1829377088 DEBUG: ResolutionUpdateCallBack - change configuration. video:1920x1080. framerate: 24.00. 1920x1080 format: BYPASS
19:40:10 T:1829377088 DEBUG: CMMALRenderer::Configure - 1920x1080->[email protected] flags:10 format:14 ext:0 orient:0
19:40:10 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x2916168
19:40:10 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x2073948
19:40:10 T:1829377088 NOTICE: Display resolution ADJUST : 1920x1080 (1920x1080) @ 24.00 - Full Screen (27) (weight: 0.000)
19:40:10 T:1829377088 DEBUG: CXBMCRenderManager::Configure - 3
19:40:10 T:1969545216 DEBUG: DecodeJpegToTexture: decoded special://skin/backgrounds/media-overlay.jpg 1280x720
19:40:10 T:1829377088 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90 m_handle 0x66b0b1f0
19:40:10 T:1829377088 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11 m_handle 0x74def410
19:40:10 T:1652552768 DEBUG: CDVDPlayer::OMXDoProcessing Resume -4503599616.00,0.21 (A:01 V:01) EOF:0 FULL:0 T:0.20
19:40:10 T:1652552768 DEBUG: OMXClock::OMXSetSpeed(1.00) pause_resume:1
19:40:10 T:1969545216 DEBUG: OMXPlayerVideo::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 (0,0,0,0,mono)
19:40:10 T:1969545216 DEBUG: COMXVideo::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 mode:1
19:40:10 T:1969545216 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv)
19:40:10 T:1969545216 DEBUG: GetMovieId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idMovie from movie where idFile=429
19:40:10 T:1969545216 DEBUG: GetEpisodeId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idEpisode from episode where idFile=429
19:40:10 T:1969545216 DEBUG: GetMusicVideoId (smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkv), query = select idMVideo from musicvideo where idFile=429
19:40:10 T:1969545216 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:40:10 T:1969545216 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:40:10 T:1969545216 DEBUG: Activating window ID: 12005
19:40:10 T:1969545216 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:40:10 T:1969545216 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:40:34 T:1669153856 DEBUG: Thread JobWorker 1669153856 terminating (autodelete)
19:41:01 T:1873802304 DEBUG: PushCecKeypress - received key e0 duration 1150
19:41:01 T:1969545216 DEBUG: OnKey: 224 (0xe0) pressed, action is Stop
19:41:01 T:1969545216 NOTICE: CDVDPlayer::CloseFile()
19:41:01 T:1969545216 NOTICE: DVDPlayer: waiting for threads to exit
19:41:01 T:1652552768 NOTICE: CDVDPlayer::OnExit()
19:41:01 T:1652552768 NOTICE: Closing stream player 2
19:41:01 T:1829377088 NOTICE: thread end: video_thread
19:41:01 T:1829377088 DEBUG: Thread OMXPlayerVideo 1829377088 terminating
19:41:01 T:1652552768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_scheduler handle 0x74def410
19:41:01 T:1652552768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_decode handle 0x68ddfd78
19:41:01 T:1652552768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_render handle 0x66b0b1f0
19:41:01 T:1652552768 DEBUG: CSMBFile::Close closing fd 10000
19:41:01 T:1652552768 DEBUG: OMXClock::OMXStop
19:41:01 T:1652552768 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.clock handle 0x697fb550
19:41:01 T:1652552768 DEBUG: OnPlayBackStopped: play state was 2, starting 0
19:41:01 T:1652552768 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
19:41:01 T:1652552768 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
19:41:01 T:1969545216 NOTICE: DVDPlayer: finished waiting
19:41:01 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:41:01 T:1969545216 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:41:01 T:1969545216 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:41:01 T:1652552768 DEBUG: Thread DVDPlayer 1652552768 terminating
19:41:01 T:1969545216 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:41:02 T:1969545216 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:41:02 T:1969545216 DEBUG: CGUIMediaWindow::GetDirectory (smb://NAS-01/share/Videos/EQ/test/issue/)
19:41:02 T:1969545216 DEBUG: ParentPath = [smb://NAS-01/share/Videos/EQ/test/issue/]
19:41:02 T:1969545216 DEBUG: RunQuery took 4 ms for 0 items query: select * from movieview WHERE c23=21
19:41:02 T:1969545216 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=21
19:41:02 T:1652552768 NOTICE: Thread BackgroundLoader start, auto delete: false
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x2990048
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(125120), nBufferAlignmen(16)
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x2938ab8
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x22bb920
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16)
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x2990048
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x2938ab8
19:41:02 T:1969545216 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x22bb920
19:41:02 T:1969545216 DEBUG: DecodeJpegToTexture: decoded special://skin/backgrounds/SKINDEFAULT.jpg 1280x720
19:41:02 T:1652552768 DEBUG: Thread BackgroundLoader 1652552768 terminating
19:41:02 T:1969545216 NOTICE: CDVDPlayer::CloseFile()
19:41:02 T:1969545216 NOTICE: DVDPlayer: waiting for threads to exit
19:41:02 T:1969545216 NOTICE: DVDPlayer: finished waiting
19:41:02 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:41:02 T:1969545216 NOTICE: CDVDPlayer::CloseFile()
19:41:02 T:1969545216 NOTICE: DVDPlayer: waiting for threads to exit
19:41:02 T:1969545216 NOTICE: DVDPlayer: finished waiting
19:41:02 T:1969545216 DEBUG: CMMALRenderer::UnInit
19:41:02 T:1959785536 INFO: CAESinkALSA - Unable to open device "default" for playback
19:41:02 T:1959785536 NOTICE: Found 1 Lists of Devices
19:41:02 T:1959785536 NOTICE: Enumerated PI devices:
19:41:02 T:1959785536 NOTICE: Device 1
19:41:02 T:1959785536 NOTICE: m_deviceName : HDMI
19:41:02 T:1959785536 NOTICE: m_displayName : HDMI
19:41:02 T:1959785536 NOTICE: m_displayNameExtra:
19:41:02 T:1959785536 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:41:02 T:1959785536 NOTICE: m_channels : FL,FR
19:41:02 T:1959785536 NOTICE: m_sampleRates : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
19:41:02 T:1959785536 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_AC3,AE_FMT_DTS,AE_FMT_EAC3
19:41:02 T:1959785536 NOTICE: Device 2
19:41:02 T:1959785536 NOTICE: m_deviceName : Analogue
19:41:02 T:1959785536 NOTICE: m_displayName : Analogue
19:41:02 T:1959785536 NOTICE: m_displayNameExtra:
19:41:02 T:1959785536 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:41:02 T:1959785536 NOTICE: m_channels : FL,FR
19:41:02 T:1959785536 NOTICE: m_sampleRates : 48000
19:41:02 T:1959785536 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:41:02 T:1959785536 NOTICE: Device 3
19:41:02 T:1959785536 NOTICE: m_deviceName : Both
19:41:02 T:1959785536 NOTICE: m_displayName : HDMI and Analogue
19:41:02 T:1959785536 NOTICE: m_displayNameExtra:
19:41:02 T:1959785536 NOTICE: m_deviceType : AE_DEVTYPE_PCM
19:41:02 T:1959785536 NOTICE: m_channels : FL,FR
19:41:02 T:1959785536 NOTICE: m_sampleRates : 48000
19:41:02 T:1959785536 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:41:02 T:1949299776 INFO: CActiveAESink::OpenSink - initialize sink
19:41:02 T:1949299776 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
19:41:02 T:1949299776 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
19:41:02 T:1949299776 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
19:41:02 T:1949299776 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x29b1308
19:41:02 T:1949299776 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignmen(16)
19:41:02 T:1949299776 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
19:41:02 T:1949299776 DEBUG: Output Device : HDMI
19:41:02 T:1949299776 DEBUG: Sample Rate : 44100
19:41:02 T:1949299776 DEBUG: Sample Format : AE_FMT_FLOAT
19:41:02 T:1949299776 DEBUG: Channel Count : 2
19:41:02 T:1949299776 DEBUG: Channel Layout: FL,FR
19:41:02 T:1949299776 DEBUG: Frames : 2205
19:41:02 T:1949299776 DEBUG: Frame Samples : 4410
19:41:02 T:1949299776 DEBUG: Frame Size : 8
19:41:02 T:1959785536 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:41:02 T:1652552768 NOTICE: Thread JobWorker start, auto delete: true
19:41:02 T:1652552768 DEBUG: DoWork - Saving file state for video item smb://NAS-01/share/Videos/EQ/test/issue/Title00-1-0_10_0-2.mkvissue is fixed in /flash/cmdline.txt with using the option
Code:
avoid_fix_ts=2but the strange thing is the relationship between handbrake 0.9.9 and 0.10.0 and that option in config.txt file, what i don't understand...
2015-02-24, 22:31
2015-02-25, 00:04
(2015-02-24, 22:31)popcornmix Wrote: Have you tried with updated firmware or with last Milhouse build? I suspect your issue is the same as the one that has been fixed.no, not yet, and can not for the next week(s)...
i just saw this report (linked from raspberrypi/firmware commit) and just tried out this option of config.txt
i tested on RaspberryPi B+ with official OpenELEC 5.0.3...
without that option:
video 0.9.9 plays smooth
video 0.10.0 plays with stuttering
with that option in config.txt:
video 0.9.9 plays smooth
video 0.10.0 plays smooth
i observed the stuttering with OpenELEC 4.2.1, 5.0.0 & 5.0.3... but only 5.0.3 i tested with that option.
