Forum
[PROBLEM] problem with xvid files - Printable Version

+- Forum (http://forum.xbian.org)
+-- Forum: Software (/forum-6.html)
+--- Forum: Kodi (/forum-18.html)
+--- Thread: [PROBLEM] problem with xvid files (/thread-1913.html)

problem with xvid files - julius02 - 21st Dec, 2013 05:18 AM

Hi guys, using latest version of xbian - fresh install. having drop frame issues on various xvid files.
here is a copy from the log for one of the files:
Does this mean anything to anyone?
Let me know if you need anything else.
BTW raspberry is rev b, running off 1500ma power supply with hard drive connected to powered hub. Does the same when played off a usb flash.
Also using dts and ac3 passthru setting and match video frame rate on.

Thanks
PHP Code:
COMXPlayer: Opening: /media/WD Passport/Movies/Dear John.avi
14:03:52 T:3041428000 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
14:03:52 T:3041428000   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
14:03:52 T:3041428000  NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
14:03:52 T:3041428000   DEBUG: LinuxRendererGL: Cleaning up GL resources
14:03:52 T:2745148480  NOTICE: Thread COMXPlayer start, auto delete: false
14:03:52 T:2745148480  NOTICE: Creating InputStream
14:03:53 T:2745148480   DEBUG: ScanForExternalSubtitles: Searching for subtitles...
14:03:53 T:3041428000   DEBUG: ------ Window Init (DialogBusy.xml) ------
14:03:53 T:2745148480   DEBUG: ScanForExternalSubtitles: END (total time: 676 ms)
14:03:53 T:2745148480  NOTICE: Creating Demuxer
14:03:53 T:2745148480   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-53-arm.so)
14:03:53 T:2745148480   DEBUG: Loading: /usr/local/lib/xbmc/system/players/dvdplayer/avcodec-53-arm.so
14:03:53 T:2745148480   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-53-arm.so)
14:03:53 T:2745148480   DEBUG: Loading: /usr/local/lib/xbmc/system/players/dvdplayer/avformat-53-arm.so
14:03:54 T:2745148480   DEBUG: Open - probing detected format [avi]
14:03:55 T:2745148480   DEBUG: Open - avformat_find_stream_info starting
14:03:55 T:2745148480   DEBUG: Open - av_find_stream_info finished
14:03:55 T:2745148480    INFO: ffmpeg[A39FA440]: Input #0, avi, from '/media/WD Passport/Movies/Dear John.avi':
14:03:55 T:2745148480    INFO: ffmpeg[A39FA440]:   Duration: 01:47:55.85, start: 0.000000, bitrate: 1808 kb/s
14:03:55 T:2745148480    INFO: ffmpeg[A39FA440]:     Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (XVID / 0x44495658), yuv420p, 640x272 [SAR 1:1 DAR 40:17], 23.98 tbr, 23.98 tbn, 23.98 tbc
14:03:55 T:2745148480    INFO: ffmpeg[A39FA440]:     Stream #0:1: Audio: ac3 ([0] [0][0] / 0x2000), 48000 Hz, 5.1(side), s16, 448 kb/s
14:03:55 T:2745148480   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.clock handle 0x27e0200 dllopen : 1
14:03:55 T:2745148480   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81
14:03:55 T:2745148480   DEBUG: OMXClock::OMXStop
14:03:55 T:2745148480   DEBUG: OMXClock::OMXSetSpeed(0.00) pause_resume:1
14:03:55 T:2745148480  NOTICE: Opening video stream: 0 source: 256
14:03:55 T:2745148480   DEBUG: DllBcm: Using omx system library
14:03:55 T:2745148480   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_decode handle 0x23b30f0 dllopen : 1
14:03:55 T:2745148480   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131
14:03:55 T:2745148480   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(120), nBufferSize(81920), nBufferAlignmen(16)
14:03:55 T:2745148480   DEBUG: COMXVideo::Open - decoder_component(0x0x23b30f0), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 0
14:03:55 T:2745148480    INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-mpeg4 width 640 height 272 profile 15 fps 23.976025
14:03:55 T:2745148480    INFO: OMXPlayerVideo::OpenDecoder fps: 23.976025 hdmi_ntsc_freqs 1
14:03:55 T:2745148480  NOTICE: Creating video thread
14:03:55 T:2830103616  NOTICE: Thread COMXPlayerVideo start, auto delete: false
14:03:55 T:2745148480  NOTICE: Opening audio stream: 1 source: 256
14:03:55 T:2745148480   DEBUG: DllBcm: Using omx system library
14:03:55 T:2830103616   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
14:03:55 T:2830103616   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
14:03:55 T:2745148480   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swresample-0-arm.so)
14:03:55 T:2745148480   DEBUG: Loading: /usr/local/lib/xbmc/system/players/dvdplayer/swresample-0-arm.so
14:03:55 T:2745148480  NOTICE: Creating audio thread
14:03:55 T:2849494080  NOTICE: Thread COMXPlayerAudio start, auto delete: false
14:03:55 T:2745148480   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /media/WD Passport/Movies/Dear John.avi
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0xa7b9c168 dllopen : 1
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
14:03:55 T:2849494080   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0xa7b2d570 dllopen : 1
14:03:55 T:2745148480   DEBUG: COMXPlayer::SetCaching - caching state 3
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
14:03:55 T:2830103616   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0
14:03:55 T:2830103616   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
14:03:55 T:2849494080   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0
14:03:55 T:2849494080   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
14:03:55 T:2830103616    INFO: COMXPlayerVideo - Stillframe left, switching to normal playback
14:03:55 T:2830103616   DEBUG: OMXVideo::Decode VDec : setStartTime 0.000000
14:03:55 T:2830103616   ERROR: Output - renderer not started
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(3686400) nBufferAlignmen(16)
14:03:55 T:2745148480   DEBUG: COMXPlayer::HandleMessages - player started 2 (tpa:0,a:0,v:1)
14:03:55 T:2849494080   DEBUG: COMXAudioCodecOMX::Decode(0x2b1c230,1792) format=1(1) chan=6 samples=1536 size=18432/18432,18432/18432,18432 data=0x27facd0,(nil),(nil),(nil),0x4800,(nil),(nil),(nil)
14:03:55 T:2849494080   DEBUG: COMXAudioCodecOMX::GetData size=18432/0/18432 cont=1 buf=0x27facd0
14:03:55 T:2849494080   DEBUG: COMXAudio::SetCodingType OMX_AUDIO_CodingPCM
14:03:55 T:2849494080   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_decode handle 0x24f5c20 dllopen : 1
14:03:55 T:2849494080   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_decode input port 120 output port 121
14:03:55 T:2849494080   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_decode) - port(120), nBufferCountMin(4), nBufferCountActual(16), nBufferSize(49152), nBufferAlignmen(16)
14:03:55 T:2849494080   DEBUG: COMXAudio::Initialize Input bps 16 samplerate 48000 channels 6 buffer size 1728000 bytes per second 576000
14:03:55 T:2849494080   DEBUG: pcm->direction      : input
14:03:55 T:2849494080   DEBUG: pcm->nPortIndex     : 0
14:03:55 T:2849494080   DEBUG: pcm->eNumData       : 0
14:03:55 T:2849494080   DEBUG: pcm->eEndian        : 1
14:03:55 T:2849494080   DEBUG: pcm->bInterleaved   : 1
14:03:55 T:2849494080   DEBUG: pcm->nBitPerSample  : 16
14:03:55 T:2849494080   DEBUG: pcm->ePCMMode       : 0
14:03:55 T:2849494080   DEBUG: pcm->nChannels      : 6
14:03:55 T:2849494080   DEBUG: pcm->nSamplingRate  : 48000
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelLF
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelRF
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelCF
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelLFE
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelLS
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelRS
14:03:55 T:2849494080   DEBUG: COMXAudio::Initialize device passthrough 0 hwdecode 0
14:03:55 T:2849494080    INFO: Audio codec  channels 6 samplerate 48000 bitspersample 16
14:03:55 T:2849494080   DEBUG: COMXAudio::Decode ADec : setStartTime 0.000000
14:03:55 T:2849494080   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_mixer handle 0x23f8948 dllopen : 1
14:03:55 T:2849494080   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231
14:03:55 T:2849494080   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_render handle 0x26fbaf8 dllopen : 1
14:03:55 T:2849494080   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100
14:03:55 T:2849494080    INFO: COMXAudio::ApplyVolume - Volume=0.00 (* 1.00 * 1.00)
14:03:55 T:2849494080   DEBUG: COMXAudio::PortSettingsChanged - Output bps 16 samplerate 48000 channels 2 buffer size 1728000 bytes per second 576000
14:03:55 T:2849494080   DEBUG: pcm->direction      : output
14:03:55 T:2849494080   DEBUG: pcm->nPortIndex     : 231
14:03:55 T:2849494080   DEBUG: pcm->eNumData       : 0
14:03:55 T:2849494080   DEBUG: pcm->eEndian        : 1
14:03:55 T:2849494080   DEBUG: pcm->bInterleaved   : 1
14:03:55 T:2849494080   DEBUG: pcm->nBitPerSample  : 16
14:03:55 T:2849494080   DEBUG: pcm->ePCMMode       : 0
14:03:55 T:2849494080   DEBUG: pcm->nChannels      : 2
14:03:55 T:2849494080   DEBUG: pcm->nSamplingRate  : 48000
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelLF
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelRF
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelCF
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelLFE
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelLR
14:03:55 T:2849494080   DEBUG: OMX_AUDIO_ChannelRR
14:03:55 T:2849494080   DEBUG: COMXAudio::PortSettingsChanged - bits:16 mode:0 channels:6 srate:48000 nopassthrough
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0xa7b2d570 dllopen : 1
14:03:55 T:3041428000   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0xa7b9c168 dllopen : 1
14:03:55 T:2849494080    INFO: COMXPlayerAudio - Switching to normal playback
14:03:55 T:2745148480   DEBUG: COMXPlayer::HandleMessages - player started RESET
14:03:55 T:2745148480  NOTICE: OMXClock using audio as reference
14:03:55 T:2745148480   DEBUG: OMXClock::OMXReset audio / video : 1 / 1 wait mask 0->3 state : 2->1
14:03:55 T:2745148480   DEBUG: COMXPlayer::HandleMessages - player started 1 (tpa:0,a:1,v:1)
14:03:55 T:2745148480   DEBUG: COMXPlayer::SetCaching - caching state 0
14:03:55 T:2849494080   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 1000
14:03:55 T:2849494080   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 32000.000000, 1)
14:03:55 T:2830103616   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000
14:03:55 T:2830103616   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 0)
14:03:55 T:2830103616   ERROR: Output - renderer not started
14:03:55 T:2830103616   ERROR: Previous line repeats 20 times.
14:03:55 T:2830103616   DEBUG: ResolutionUpdateCallBack - change configuration. video:1920x1080. framerate: 23.98. 640x272 format: BYPASS
14:03:55 T:2830103616  NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (16)
14:03:55 T:2830103616  NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
14:03:55 T:2830103616   DEBUG: CXBMCRenderManager::EnableBuffering - 1
14:03:55 T:2997077056   DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 3000
14:03:55 T:2997077056   DEBUG: CecLogMessage - physical address changed to 3000
14:03:55 T:2997077056   DEBUG: CecLogMessage - physical address unchanged (3000)
14:03:55 T:2830103616   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_render handle 0x271eb68 dllopen : 1
14:03:55 T:2830103616   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90
14:03:55 T:2830103616   DEBUG: COMXVideo::PortSettingsChanged - 640x272@23.98 interlace:0 deinterlace:0
14:03:55 T:2830103616   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_scheduler handle 0x25109b0 dllopen : 1
14:03:55 T:2830103616   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11
14:03:55 T:3041428000  NOTICE: Using GL_TEXTURE_2D
14:03:55 T:3041428000   DEBUG: GL: Requested render method: 0
14:03:55 T:3041428000  NOTICE: GL: Using BYPASS render method
14:03:55 T:3041428000  NOTICE: GL: NPOT texture support detected
14:03:55 T:2745148480   DEBUG: Resume 0.33,1.53 (A:01 V:01) EOF:0 FULL:0 T:0.20
14:03:55 T:2745148480   DEBUG: OMXClock::OMXSetSpeed(1.00) pause_resume:1
14:03:55 T:3041428000    INFO: COMXAudio::ApplyVolume - Volume=1.00 (* 1.00 * 1.00)
14:03:55 T:3041428000    INFO: Previous line repeats 1 times.
14:03:55 T:3041428000   DEBUG: Activating window ID: 12005
14:03:55 T:3041428000   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
14:03:55 T:3041428000   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
14:03:56 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:03:56 T:3041428000   DEBUG: dest_rect.x_offset 0 dest_rect.y_offset 132 dest_rect.width 1920 dest_rect.height 816
14:03:56 T:3041428000   DEBUG: CGUIInfoManager::SetCurrentMovie(/media/WD Passport/Movies/Dear John.avi)
14:03:56 T:3041428000   DEBUG: GetMovieId (/media/WD Passport/Movies/Dear John.avi), query = select idMovie from movie where idFile=27
14:03:56 T:3041428000   DEBUG: GetEpisodeId (/media/WD Passport/Movies/Dear John.avi), query = select idEpisode from episode where idFile=27
14:03:56 T:3041428000   DEBUG: GetMusicVideoId (/media/WD Passport/Movies/Dear John.avi), query = select idMVideo from musicvideo where idFile=27
14:03:56 T:3041428000   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
14:03:56 T:3041428000   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
14:03:56 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:03:56 T:3041428000   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
14:03:57 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:10 T:3041428000   DEBUG: Previous line repeats 131 times.
14:04:10 T:3041428000    INFO: CheckIdle - Closing session to http://www.google.com (easy=0x25451e0, multi=(nil))
14:04:10 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:20 T:2772431936   DEBUG: Previous line repeats 104 times.
14:04:20 T:2772431936   DEBUG: Thread Jobworker 2772431936 terminating (autodelete)
14:04:20 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:27 T:2988688448   DEBUG: Previous line repeats 71 times.
14:04:27 T:2988688448   DEBUG: CecLogMessage - received data: header:00030004 p0:00004401 p1:00000000 p2:00000000 p3:00000000 reason:4
14:04:27 T:2962224192   DEBUG: CecLogMessage - >> 01:44:00
14:04:27 T:2962224192   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
14:04:27 T:2962224192   DEBUG: CecLogMessage - key pressed: select (0)
14:04:27 T:2962224192   DEBUG: PushCecKeypress - received key  b duration 0
14:04:27 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:27 T:3041428000   DEBUG: OnKey: 11 (0b) pressed, action is OSD
14:04:27 T:3041428000   DEBUG: ------ Window Init (VideoOSD.xml) ------
14:04:27 T:2772431936  NOTICE: Thread Jobworker start, auto delete: true
14:04:27 T:2772431936   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0x26bca00 dllopen : 1
14:04:27 T:2772431936   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
14:04:27 T:2772431936   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0x23667e8 dllopen : 1
14:04:27 T:2772431936   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
14:04:27 T:2772431936   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
14:04:27 T:2988688448   DEBUG: CecLogMessage - received data: header:00030008 p0:00004501 p1:00000000 p2:00000000 p3:00000000 reason:8
14:04:27 T:2962224192   DEBUG: CecLogMessage - >> 01:8b:00
14:04:27 T:2962224192   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B)
14:04:27 T:2962224192   DEBUG: CecLogMessage - key released: select (0)
14:04:27 T:2962224192   DEBUG: PushCecKeypress - received key  b duration 228
14:04:28 T:2772431936   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(139264) nBufferAlignmen(16)
14:04:28 T:2772431936   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x23667e8 dllopen : 1
14:04:28 T:2772431936   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x26bca00 dllopen : 1
14:04:28 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:31 T:2988688448   DEBUG: Previous line repeats 25 times.
14:04:31 T:2988688448   DEBUG: CecLogMessage - received data: header:00030004 p0:000D4401 p1:00000000 p2:00000000 p3:00000000 reason:4
14:04:31 T:2962224192   DEBUG: CecLogMessage - >> 01:44:0d
14:04:31 T:2962224192   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
14:04:31 T:2962224192   DEBUG: CecLogMessage - key pressed: exit (d)
14:04:31 T:2962224192   DEBUG: PushCecKeypress - received key d8 duration 0
14:04:31 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:31 T:3041428000   DEBUG: OnKey: menu (d8) pressed, action is Back
14:04:31 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:31 T:2988688448   DEBUG: Previous line repeats 1 times.
14:04:31 T:2988688448   DEBUG: CecLogMessage - received data: header:00030008 p0:000D4501 p1:00000000 p2:00000000 p3:00000000 reason:8
14:04:31 T:2962224192   DEBUG: CecLogMessage - >> 01:8b:0d
14:04:31 T:2962224192   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B)
14:04:31 T:2962224192   DEBUG: CecLogMessage - key released: exit (d)
14:04:31 T:2962224192   DEBUG: PushCecKeypress - received key d8 duration 334
14:04:31 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:31 T:3041428000   DEBUG: ------ Window Deinit (VideoOSD.xml) ------
14:04:31 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:04:58 T:2772431936   DEBUG: Previous line repeats 261 times.
14:04:58 T:2772431936   DEBUG: Thread Jobworker 2772431936 terminating (autodelete)
14:04:58 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4 
here is a copy of the log from another xvid file which has the same frame drop issue:
PHP Code:
14:18:21 T:3041428000  NOTICE: COMXPlayer: Opening: /media/WD Passport/Movies/Edge of Darkness.avi
14:18:21 T:3041428000 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
14:18:21 T:3041428000   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
14:18:21 T:3041428000  NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
14:18:21 T:3041428000   DEBUG: LinuxRendererGL: Cleaning up GL resources
14:18:21 T:2745148480  NOTICE: Thread COMXPlayer start, auto delete: false
14:18:21 T:2745148480  NOTICE: Creating InputStream
14:18:21 T:3041428000   DEBUG: ------ Window Init (DialogBusy.xml) ------
14:18:21 T:2745148480   DEBUG: ScanForExternalSubtitles: Searching for subtitles...
14:18:22 T:2745148480   DEBUG: ScanForExternalSubtitles: END (total time: 1487 ms)
14:18:22 T:2745148480  NOTICE: Creating Demuxer
14:18:22 T:2745148480   DEBUG: Open - probing detected format [avi]
14:18:25 T:2745148480   DEBUG: Open - avformat_find_stream_info starting
14:18:25 T:2745148480   DEBUG: Open - av_find_stream_info finished
14:18:25 T:2745148480    INFO: ffmpeg[A39FA440]: Input #0, avi, from '/media/WD Passport/Movies/Edge of Darkness.avi':
14:18:25 T:2745148480    INFO: ffmpeg[A39FA440]:   Metadata:
14:18:25 T:2745148480    INFO: ffmpeg[A39FA440]:     encoder         : VirtualDubMod 1.5.10.2 (build 2540/release)
14:18:25 T:2745148480    INFO: ffmpeg[A39FA440]:   Duration: 01:56:48.09, start: 0.000000, bitrate: 830 kb/s
14:18:25 T:2745148480    INFO: ffmpeg[A39FA440]:     Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (XVID / 0x44495658), yuv420p, 608x256 [SAR 1:1 DAR 19:8], 23.98 tbr, 23.98 tbn, 23.98 tbc
14:18:25 T:2745148480    INFO: ffmpeg[A39FA440]:     Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16, 160 kb/s
14:18:25 T:2745148480   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.clock handle 0x23fde58 dllopen : 1
14:18:25 T:2745148480   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81
14:18:25 T:2745148480   DEBUG: OMXClock::OMXStop
14:18:25 T:2745148480   DEBUG: OMXClock::OMXSetSpeed(0.00) pause_resume:1
14:18:25 T:2745148480  NOTICE: Opening video stream: 0 source: 256
14:18:25 T:2745148480   DEBUG: DllBcm: Using omx system library
14:18:25 T:2745148480   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_decode handle 0x2156370 dllopen : 1
14:18:25 T:2745148480   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131
14:18:25 T:2745148480   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(120), nBufferSize(81920), nBufferAlignmen(16)
14:18:25 T:2745148480   DEBUG: COMXVideo::Open - decoder_component(0x0x2156370), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 0
14:18:25 T:2745148480    INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-mpeg4 width 608 height 256 profile 15 fps 23.976025
14:18:25 T:2745148480    INFO: OMXPlayerVideo::OpenDecoder fps: 23.976025 hdmi_ntsc_freqs 1
14:18:25 T:2745148480  NOTICE: Creating video thread
14:18:25 T:2772431936  NOTICE: Thread COMXPlayerVideo start, auto delete: false
14:18:25 T:2745148480  NOTICE: Opening audio stream: 1 source: 256
14:18:25 T:2745148480   DEBUG: DllBcm: Using omx system library
14:18:25 T:2772431936   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
14:18:25 T:2772431936   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
14:18:25 T:2745148480  NOTICE: Creating audio thread
14:18:25 T:2857882688  NOTICE: Thread COMXPlayerAudio start, auto delete: false
14:18:25 T:2745148480   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /media/WD Passport/Movies/Edge of Darkness.avi
14:18:25 T:2745148480   DEBUG: COMXPlayer::SetCaching - caching state 3
14:18:25 T:2857882688   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
14:18:25 T:2857882688   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0
14:18:25 T:2857882688   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
14:18:25 T:2772431936   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0
14:18:25 T:2772431936   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
14:18:25 T:2857882688   DEBUG: COMXAudioCodecOMX::Decode(0x2af79f0,480) format=1(1) chan=2 samples=1152 size=4608/4608,4608/4608,4608 data=0x2b1b5d0,(nil),(nil),(nil),0x1200,(nil),(nil),(nil)
14:18:25 T:2857882688   DEBUG: COMXAudioCodecOMX::GetData size=4608/0/4608 cont=1 buf=0x2b1b5d0
14:18:25 T:2772431936    INFO: COMXPlayerVideo - Stillframe left, switching to normal playback
14:18:25 T:2772431936   DEBUG: OMXVideo::Decode VDec : setStartTime 0.000000
14:18:25 T:2772431936   ERROR: Output - renderer not started
14:18:25 T:2745148480   DEBUG: COMXPlayer::HandleMessages - player started 2 (tpa:0,a:0,v:1)
14:18:25 T:2857882688   DEBUG: COMXAudio::SetCodingType OMX_AUDIO_CodingPCM
14:18:25 T:2857882688   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_decode handle 0x21475e0 dllopen : 1
14:18:25 T:2857882688   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_decode input port 120 output port 121
14:18:25 T:2857882688   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_decode) - port(120), nBufferCountMin(4), nBufferCountActual(16), nBufferSize(49152), nBufferAlignmen(16)
14:18:25 T:2857882688   DEBUG: COMXAudio::Initialize Input bps 16 samplerate 48000 channels 2 buffer size 576000 bytes per second 192000
14:18:25 T:2857882688   DEBUG: pcm->direction      : input
14:18:25 T:2857882688   DEBUG: pcm->nPortIndex     : 0
14:18:25 T:2857882688   DEBUG: pcm->eNumData       : 0
14:18:25 T:2857882688   DEBUG: pcm->eEndian        : 1
14:18:25 T:2857882688   DEBUG: pcm->bInterleaved   : 1
14:18:25 T:2857882688   DEBUG: pcm->nBitPerSample  : 16
14:18:25 T:2857882688   DEBUG: pcm->ePCMMode       : 0
14:18:25 T:2857882688   DEBUG: pcm->nChannels      : 2
14:18:25 T:2857882688   DEBUG: pcm->nSamplingRate  : 48000
14:18:25 T:2857882688   DEBUG: OMX_AUDIO_ChannelLF
14:18:25 T:2857882688   DEBUG: OMX_AUDIO_ChannelRF
14:18:25 T:2857882688   DEBUG: COMXAudio::Initialize device passthrough 0 hwdecode 0
14:18:25 T:2857882688    INFO: Audio codec  channels 2 samplerate 48000 bitspersample 16
14:18:25 T:2857882688   DEBUG: COMXAudio::Decode ADec : setStartTime 0.000000
14:18:25 T:2857882688   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_mixer handle 0xae1907e8 dllopen : 1
14:18:25 T:2857882688   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231
14:18:25 T:2857882688   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_render handle 0xae190848 dllopen : 1
14:18:25 T:2857882688   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100
14:18:25 T:2857882688    INFO: COMXAudio::ApplyVolume - Volume=0.00 (* 1.00 * 1.00)
14:18:25 T:2857882688   DEBUG: COMXAudio::PortSettingsChanged - Output bps 16 samplerate 48000 channels 2 buffer size 576000 bytes per second 192000
14:18:25 T:2857882688   DEBUG: pcm->direction      : output
14:18:25 T:2857882688   DEBUG: pcm->nPortIndex     : 231
14:18:25 T:2857882688   DEBUG: pcm->eNumData       : 0
14:18:25 T:2857882688   DEBUG: pcm->eEndian        : 1
14:18:25 T:2857882688   DEBUG: pcm->bInterleaved   : 1
14:18:25 T:2857882688   DEBUG: pcm->nBitPerSample  : 16
14:18:25 T:2857882688   DEBUG: pcm->ePCMMode       : 0
14:18:25 T:2857882688   DEBUG: pcm->nChannels      : 2
14:18:25 T:2857882688   DEBUG: pcm->nSamplingRate  : 48000
14:18:25 T:2857882688   DEBUG: OMX_AUDIO_ChannelLF
14:18:25 T:2857882688   DEBUG: OMX_AUDIO_ChannelRF
14:18:25 T:2857882688   DEBUG: COMXAudio::PortSettingsChanged - bits:16 mode:0 channels:2 srate:48000 nopassthrough
14:18:26 T:3041428000    INFO: COMXAudio::ApplyVolume - Volume=1.00 (* 1.00 * 1.00)
14:18:26 T:2745148480    INFO: Previous line repeats 1 times.
14:18:26 T:2745148480   DEBUG: COMXPlayer::HandleMessages - player started RESET
14:18:26 T:2745148480  NOTICE: OMXClock using audio as reference
14:18:26 T:2745148480   DEBUG: OMXClock::OMXReset audio / video : 1 / 1 wait mask 0->3 state : 2->1
14:18:26 T:2745148480   DEBUG: COMXPlayer::HandleMessages - player started 1 (tpa:0,a:1,v:1)
14:18:26 T:2745148480   DEBUG: COMXPlayer::SetCaching - caching state 4
14:18:26 T:2857882688   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 1000
14:18:26 T:2857882688   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 24000.000000, 1)
14:18:26 T:2772431936   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000
14:18:26 T:2772431936   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 0)
14:18:26 T:2772431936   ERROR: Output - renderer not started
14:18:26 T:2772431936   ERROR: Previous line repeats 19 times.
14:18:26 T:2772431936   DEBUG: ResolutionUpdateCallBack - change configuration. video:1920x1080. framerate: 23.98. 608x256 format: BYPASS
14:18:26 T:2772431936  NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (16)
14:18:26 T:2772431936  NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
14:18:26 T:2772431936   DEBUG: CXBMCRenderManager::EnableBuffering - 1
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0xa8fcaa18 dllopen : 1
14:18:26 T:2997077056   DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 3000
14:18:26 T:2997077056   DEBUG: CecLogMessage - physical address changed to 3000
14:18:26 T:2997077056   DEBUG: CecLogMessage - physical address unchanged (3000)
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
14:18:26 T:2772431936   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_render handle 0xa5fd8d38 dllopen : 1
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0xa8b78398 dllopen : 1
14:18:26 T:2772431936   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90
14:18:26 T:2772431936   DEBUG: COMXVideo::PortSettingsChanged - 608x256@23.98 interlace:0 deinterlace:0
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
14:18:26 T:2772431936   DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_scheduler handle 0xa5fd8df8 dllopen : 1
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
14:18:26 T:2772431936   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(3686400) nBufferAlignmen(16)
14:18:26 T:2745148480   DEBUG: Resume 0.22,0.83 (A:01 V:01) EOF:0 FULL:0 T:0.20
14:18:26 T:2745148480   DEBUG: OMXClock::OMXSetSpeed(1.00) pause_resume:1
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0xa8b78398 dllopen : 1
14:18:26 T:3041428000   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0xa8fcaa18 dllopen : 1
14:18:26 T:3041428000  NOTICE: Using GL_TEXTURE_2D
14:18:26 T:3041428000   DEBUG: GL: Requested render method: 0
14:18:26 T:3041428000  NOTICE: GL: Using BYPASS render method
14:18:26 T:3041428000  NOTICE: GL: NPOT texture support detected
14:18:26 T:2857882688    INFO: COMXPlayerAudio - Switching to normal playback
14:18:26 T:2745148480   DEBUG: COMXPlayer::SetCaching - caching state 0
14:18:26 T:3041428000   DEBUG: CGUIInfoManager::SetCurrentMovie(/media/WD Passport/Movies/Edge of Darkness.avi)
14:18:27 T:3041428000   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
14:18:27 T:3041428000   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
14:18:27 T:3041428000   DEBUG: Activating window ID: 12005
14:18:27 T:3041428000   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
14:18:27 T:3041428000   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
14:18:27 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:18:27 T:3041428000   DEBUG: dest_rect.x_offset 0 dest_rect.y_offset 137 dest_rect.width 1920 dest_rect.height 809
14:18:27 T:3041428000   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
14:18:28 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:18:55 T:2830103616   DEBUG: Previous line repeats 272 times.
14:18:55 T:2830103616   DEBUG: ExtractThumb - decode failed in /media/WD Passport/Movies/World War Z.mp4 after 161 packets.
14:18:55 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4
14:18:55 T:2830103616   DEBUG: ExtractThumb - measured 38617 ms to extract thumb from file </media/WD Passport/Movies/World War Z.mp4> in 161 packets.
14:18:55 T:3041428000   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 4 


RE: problem with xvid files - mk01 - 21st Dec, 2013 07:34 PM

match video frame rate on = adjust display refresh rate to match video ?

or sync playback to match display ?

also do you have
Code:
<video>
     <adjustrefreshrate>  
       <override>
         <fps>23.976</fps>
         <refresh>24.0</refresh>
       </override>
     </adjustrefreshrate>
   </video>

in advancedsettings ?

RE: problem with xvid files - julius02 - 21st Dec, 2013 09:44 PM

Yes I have adjust display refresh rate to match video = on... sync plauback is not selected = off.

I have checked the advancedsettings.xml and yes that text is included.
What is it that is causing the problem? What does the text that you are referring to in the advancedsettings mean in simple terms lol

I have tried raspbmc just out of curiosity and I can say the same is happening with that???

Any help is greatly appreciated.

Thanks