Linux RPi OpenELEC videos unplayable
#1
RPi SMB link to Windows7 iTunes .m4v files works OK with some but not others, all OK on Windows7 laptop.

The only clue I have is that none of the unplayable files have thumbnails.

This is part of the XBMC log for "01 Heart of Glass", which failed to start (others appear to run, but with no audio or video):-

################################################################################
# ... output of cat /storage/.xbmc/temp/xbmc.log
# OpenELEC release: RPi.arm-devel-20130211162131-r13263
# OpenELEC git: ddad2a85e01081726f0b273d4023b590534260c5
################################################################################
08:58:37 T:3043323904 NOTICE: -----------------------------------------------------------------------
08:58:37 T:3043323904 NOTICE: Starting XBMC (12.0 Git:fb595f2), Platform: Linux (OpenELEC - Version: devel-20130211162131-r13263, 3.6.11 armv6l). Built on Feb 11 2013
08:58:37 T:3043323904 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
08:58:37 T:3043323904 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
08:58:37 T:3043323904 NOTICE: special://masterprofile/ is mapped to: /storage/.xbmc/userdata
08:58:37 T:3043323904 NOTICE: special://home/ is mapped to: /storage/.xbmc
08:58:37 T:3043323904 NOTICE: special://temp/ is mapped to: /storage/.xbmc/temp
08:58:37 T:3043323904 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
08:58:37 T:3043323904 NOTICE: Local hostname: openelec
08:58:37 T:3043323904 NOTICE: Log File is located: /storage/.xbmc/temp/xbmc.log
08:58:37 T:3043323904 NOTICE: -----------------------------------------------------------------------
08:58:38 T:3043323904 NOTICE: load settings...
08:58:42 T:3043323904 NOTICE: special://profile/ is mapped to: special://masterprofile/
08:58:42 T:3043323904 NOTICE: loading special://masterprofile/guisettings.xml
08:58:44 T:3043323904 NOTICE: Getting hardware information now...
08:58:44 T:3043323904 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
08:58:44 T:3043323904 NOTICE: Loaded playercorefactory configuration
08:58:44 T:3043323904 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
08:58:44 T:3043323904 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
08:58:44 T:3043323904 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
08:58:44 T:3043323904 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
<?xml version="1.0" encoding="UTF-8" ?>
<advancedsettings>
<cputempcommand>cputemp</cputempcommand>
<gputempcommand>cputemp</gputempcommand>
<showexitbutton>false</showexitbutton>
<destroywindowcontrols>false</destroywindowcontrols>
<fanartres>540</fanartres>
<imageres>256</imageres>
<bginfoloadermaxthreads>2</bginfoloadermaxthreads>
<useddsfanart>false</useddsfanart>
<video>
<defaultplayer>omxplayer</defaultplayer>
<defaultdvdplayer>omxplayer</defaultdvdplayer>
</video>
<audio>
<defaultplayer>omxplayer</defaultplayer>
<streamsilence>false</streamsilence>
</audio>
<network>
<cachemembuffersize>5282880</cachemembuffersize>
</network>
<samba>
<clienttimeout>30</clienttimeout>
</samba>
</advancedsettings>
08:58:44 T:3043323904 NOTICE: Getting hardware information now...
08:58:45 T:3043323904 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
08:58:45 T:3043323904 NOTICE: Default DVD Player: omxplayer
08:58:45 T:3043323904 NOTICE: Default Video Player: omxplayer
08:58:45 T:3043323904 NOTICE: Default Audio Player: omxplayer
08:58:45 T:3043323904 NOTICE: Disabled debug logging due to GUI setting. Level 0.
08:58:45 T:3043323904 NOTICE: Log level changed to 0
08:58:45 T:3043323904 NOTICE: Loading media sources from special://masterprofile/sources.xml
08:58:46 T:3043323904 ERROR: Error: Requested setting (audiooutput.guisoundmode) was not found. It must be case-sensitive
08:58:46 T:3043323904 NOTICE: Running database version Addons15
08:58:48 T:3043323904 NOTICE: Register - new cec device registered on rpi->RPI: Raspberry Pi CEC Adapter (2708:1001)
08:58:48 T:3033158752 NOTICE: Thread CEC Adapter start, auto delete: false
08:58:49 T:3023041632 NOTICE: Thread XBMC Peripherals start, auto delete: false
08:58:52 T:3033158752 ERROR: CecLogMessage - failed to release the previous LA
08:58:52 T:3033158752 ERROR: CecLogMessage - could not open a connection (try 1)
08:58:52 T:3043323904 WARNING: could not open /dev/watchdog
08:58:52 T:3043323904 NOTICE: Raspberry PI firmware version: Jan 30 2013 12:22:28
Copyright © 2012 Broadcom
version 366105 (release)
08:58:53 T:3043323904 NOTICE: InitWindowSystem: Using EGL Implementation: raspberrypi
08:58:53 T:3043323904 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 60.000000 Hz
08:58:53 T:3043323904 NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz
08:58:53 T:3043323904 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
08:58:53 T:3043323904 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 60.000000 Hz
08:58:53 T:3043323904 NOTICE: Found resolution 720 x 576 for display 0 with 720 x 576 @ 50.000000 Hz
08:58:53 T:3043323904 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz
08:58:53 T:3043323904 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 50.000000 Hz
08:58:53 T:3043323904 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 24.000000 Hz
08:58:53 T:3043323904 NOTICE: Found ([email protected]) at 21, setting to RES_DESKTOP at 16
08:58:53 T:3043323904 NOTICE: Checking resolution 19
08:58:53 T:3043323904 NOTICE: GL_VENDOR = Broadcom
08:58:53 T:3043323904 NOTICE: GL_RENDERER = VideoCore IV HW
08:58:53 T:3043323904 NOTICE: GL_VERSION = OpenGL ES 2.0
08:58:53 T:3043323904 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
08:58:53 T:3043323904 NOTICE: GL_EXTENSIONS = GL_OES_compressed_ETC1_RGB8_texture GL_OES_compressed_paletted_texture GL_OES_texture_npot GL_OES_depth24 GL_OES_vertex_half_float GL_OES_EGL_image GL_OES_EGL_image_external GL_EXT_discard_framebuffer GL_OES_rgb8_rgba8 GL_OES_depth32 GL_OES_mapbuffer GL_EXT_texture_format_BGRA8888 GL_APPLE_rgb_422 GL_EXT_debug_marker
08:58:54 T:2962904160 NOTICE: Thread CEC Adapter Update Thread start, auto delete: false
08:58:57 T:3043323904 NOTICE: Running database version Addons15
08:58:57 T:3043323904 NOTICE: Running database version ViewModes4
08:58:57 T:3043323904 NOTICE: Running database version Textures13
08:58:58 T:3043323904 NOTICE: Running database version MyMusic32
08:58:58 T:3043323904 NOTICE: Running database version MyVideos75
08:58:58 T:3043323904 NOTICE: Running database version TV22
08:58:58 T:3043323904 NOTICE: Running database version Epg7
08:58:58 T:3043323904 NOTICE: start dvd mediatype detection
08:58:58 T:3043323904 NOTICE: initializing playlistplayer
08:58:58 T:3043323904 NOTICE: DONE initializing playlistplayer
08:58:58 T:2952893536 NOTICE: Thread CDetectDVDMedia start, auto delete: false
08:59:01 T:3043323904 NOTICE: UpdateLibraries - Starting music library startup scan
08:59:01 T:3043323904 NOTICE: initialize done
08:59:01 T:3043323904 NOTICE: Running the application...
08:59:01 T:2952496224 NOTICE: Thread CMusicInfoScanner start, auto delete: false
08:59:03 T:2944107616 NOTICE: Thread Jobworker start, auto delete: true
08:59:03 T:3043323904 NOTICE: ES: Starting event server
08:59:03 T:2927330400 NOTICE: Thread CTCPServer start, auto delete: false
08:59:03 T:2935719008 NOTICE: Thread CEventServer start, auto delete: false
08:59:03 T:2935719008 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
08:59:03 T:2935719008 NOTICE: UDP: Listening on port 9777
08:59:03 T:3043323904 NOTICE: starting zeroconf publishing
08:59:03 T:2902164576 NOTICE: Thread CMusicInfoScanner start, auto delete: false
08:59:03 T:2910553184 NOTICE: Thread Jobworker start, auto delete: true
08:59:19 T:2952496224 NOTICE: My Music: Scanning for music info using worker thread, operation took 00:17
09:00:51 T:3043323904 NOTICE: Samba is idle. Closing the remaining connections
09:01:13 T:2910553184 NOTICE: Thread Background Loader start, auto delete: false
09:01:16 T:2910553184 NOTICE: Thread Jobworker start, auto delete: true
09:01:16 T:2944107616 NOTICE: Thread Background Loader start, auto delete: false
09:01:25 T:2952496224 NOTICE: Previous line repeats 1 times.
09:01:25 T:2952496224 NOTICE: Thread Jobworker start, auto delete: true
09:01:26 T:3043323904 NOTICE: COMXPlayer: Opening: smb://LAPTOP/Users/Public/Videos/01 Heart of Glass.m4v
09:01:27 T:2944107616 NOTICE: Thread COMXPlayer start, auto delete: false
09:01:27 T:2944107616 NOTICE: Creating InputStream
09:01:28 T:2944107616 NOTICE: Creating Demuxer
09:01:29 T:2944107616 NOTICE: Opening video stream: 1 source: 256
09:01:29 T:2944107616 ERROR: OMXPlayerVideo : Error open video output
09:01:29 T:2944107616 NOTICE: OMXClock using video as reference
09:01:29 T:2944107616 WARNING: OpenVideoStream - Unsupported stream 1. Stream disabled.
09:01:29 T:2944107616 NOTICE: Opening audio stream: 0 source: 256
09:01:29 T:2944107616 NOTICE: Creating audio thread
09:01:29 T:2902164576 NOTICE: Thread COMXPlayerAudio start, auto delete: false
09:01:29 T:2944107616 WARNING: CDVDMessageQueue(video):Tongueut MSGQ_NOT_INITIALIZED
09:01:29 T:2902164576 WARNING: Previous line repeats 1 times.
09:01:29 T:2902164576 NOTICE: OMXClock using audio as reference
09:02:10 T:2902164576 ERROR: OMXPlayerAudio : Error open audio output
09:02:10 T:2902164576 NOTICE: OMXClock using video as reference
09:02:10 T:2944107616 NOTICE: COMXPlayer::OnExit()
09:02:10 T:2944107616 NOTICE: OMXPlayer: closing audio stream
09:02:10 T:2944107616 NOTICE: Closing audio stream
09:02:10 T:2902164576 NOTICE: thread end: OMXPlayerAudio::OnExit()
09:02:10 T:2944107616 NOTICE: OMXClock using video as reference
09:02:10 T:2944107616 NOTICE: COMXPlayer::OnExit() deleting demuxer
09:02:10 T:2944107616 NOTICE: COMXPlayer::OnExit() deleting input stream
09:02:11 T:3043323904 NOTICE: OMXClock using video as reference
09:02:11 T:2944107616 NOTICE: Previous line repeats 1 times.
09:02:11 T:2944107616 NOTICE: Thread Jobworker start, auto delete: true
09:02:14 T:3043323904 NOTICE: COMXPlayer: Opening: smb://LAPTOP/Users/Public/Videos/13 Owner of a Lonely Heart.m4v
09:02:14 T:2902164576 NOTICE: Thread COMXPlayer start, auto delete: false
09:02:14 T:2902164576 NOTICE: Creating InputStream
09:02:15 T:2902164576 NOTICE: Creating Demuxer
09:02:16 T:2902164576 NOTICE: Opening video stream: 1 source: 256
09:02:16 T:2902164576 NOTICE: OMXClock using video as reference
09:02:16 T:2902164576 NOTICE: Creating video thread
09:02:16 T:2889466976 NOTICE: Thread COMXPlayerVideo start, auto delete: false
09:02:16 T:2902164576 NOTICE: Opening audio stream: 0 source: 256
09:02:16 T:2902164576 NOTICE: Creating audio thread
09:02:16 T:2881078368 NOTICE: Thread COMXPlayerAudio start, auto delete: false
09:02:17 T:2889466976 NOTICE: Display resolution USER : 1920x1080 @ 60.00 - Full Screen (19)
09:02:17 T:2881078368 NOTICE: OMXClock using audio as reference
09:02:17 T:3043323904 NOTICE: Using GL_TEXTURE_2D
09:02:17 T:3043323904 NOTICE: GL: Using BYPASS render method
09:02:17 T:3043323904 NOTICE: GL: NPOT texture support detected
09:02:17 T:2889466976 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete
09:02:18 T:2889466976 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame
09:05:21 T:2902164576 WARNING: Previous line repeats 24 times.
09:05:21 T:2902164576 NOTICE: COMXPlayer::OnExit()
09:05:21 T:2902164576 NOTICE: OMXPlayer: eof, waiting for queues to empty
09:05:21 T:2902164576 NOTICE: OMXPlayer: closing audio stream
09:05:21 T:2902164576 NOTICE: Closing audio stream
09:05:21 T:2902164576 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
09:05:21 T:2881078368 NOTICE: thread end: OMXPlayerAudio::OnExit()
09:05:21 T:2902164576 NOTICE: OMXClock using video as reference
09:05:21 T:2902164576 NOTICE: OMXPlayer: closing video stream
09:05:21 T:2902164576 NOTICE: Closing video stream
09:05:21 T:2902164576 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
09:05:21 T:2889466976 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
09:05:21 T:2889466976 NOTICE: thread end: video_thread
09:05:21 T:2902164576 NOTICE: OMXClock using video as reference
09:05:21 T:2902164576 NOTICE: COMXPlayer::OnExit() deleting demuxer
09:05:21 T:2902164576 NOTICE: COMXPlayer::OnExit() deleting input stream
09:05:22 T:2902164576 NOTICE: Thread Background Loader start, auto delete: false
09:05:22 T:2889466976 NOTICE: Thread Jobworker start, auto delete: true
09:05:24 T:3043323904 NOTICE: Previous line repeats 2 times.
09:05:24 T:3043323904 NOTICE: OMXClock using video as reference
09:06:06 T:2902164576 NOTICE: Previous line repeats 1 times.
09:06:06 T:2902164576 NOTICE: Thread Background Loader start, auto delete: false
09:07:12 T:3043323904 NOTICE: Previous line repeats 1 times.
09:07:12 T:3043323904 NOTICE: Samba is idle. Closing the remaining connections
09:09:08 T:3043323904 ERROR: GetDirectory - Error getting /storage/.xbmc/media/Fonts/
09:09:08 T:3043323904 ERROR: GetDirectory - Error getting special://home/media/Fonts/
09:09:27 T:2889466976 NOTICE: Thread Jobworker start, auto delete: true
09:10:38 T:3043323904 ERROR: GetDirectory - Error getting /storage/.xbmc/media/Fonts/
09:10:38 T:3043323904 ERROR: GetDirectory - Error getting special://home/media/Fonts/
09:10:53 T:2889466976 NOTICE: Thread Jobworker start, auto delete: true
09:11:51 T:2889466976 NOTICE: Thread XBPyThread start, auto delete: false
09:11:53 T:2889466976 NOTICE: -->Python Interpreter Initialized<--
09:11:57 T:2952496224 NOTICE: Thread XBPyThread start, auto delete: false
09:11:58 T:2952496224 NOTICE: -->Python Interpreter Initialized<--
09:13:18 T:2952496224 NOTICE: Thread Jobworker start, auto delete: true
09:13:22 T:2889466976 NOTICE: Thread Background Loader start, auto delete: false
09:13:22 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://disabled/folder.jpg
09:13:22 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://enabled/folder.jpg
09:13:22 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://repos/folder.jpg
09:13:22 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://install/folder.jpg
09:13:22 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://search/folder.jpg
09:13:33 T:2889466976 NOTICE: Thread Background Loader start, auto delete: false
09:14:01 T:2889466976 NOTICE: Previous line repeats 3 times.
09:14:01 T:2889466976 NOTICE: Thread Jobworker start, auto delete: true
09:14:47 T:2902164576 NOTICE: Previous line repeats 1 times.
09:14:47 T:2902164576 NOTICE: Thread Background Loader start, auto delete: false
09:14:51 T:2902164576 NOTICE: Previous line repeats 1 times.
09:14:51 T:2902164576 WARNING: CreateLoader - Unsupported protocol(addons) in addons://disabled/folder.jpg
09:14:51 T:2902164576 WARNING: CreateLoader - Unsupported protocol(addons) in addons://repos/folder.jpg
09:14:51 T:2902164576 WARNING: CreateLoader - Unsupported protocol(addons) in addons://install/folder.jpg
09:14:52 T:2902164576 WARNING: CreateLoader - Unsupported protocol(addons) in addons://search/folder.jpg
09:14:55 T:2902164576 NOTICE: Thread Background Loader start, auto delete: false
09:15:11 T:2902164576 NOTICE: Previous line repeats 1 times.
09:15:11 T:2902164576 NOTICE: Thread Jobworker start, auto delete: true
09:15:17 T:2910553184 NOTICE: Thread Background Loader start, auto delete: false
09:15:21 T:2910553184 NOTICE: Previous line repeats 1 times.
09:15:21 T:2910553184 WARNING: CreateLoader - Unsupported protocol(addons) in addons://repos/folder.jpg
09:15:21 T:2910553184 WARNING: CreateLoader - Unsupported protocol(addons) in addons://install/folder.jpg
09:15:21 T:2910553184 WARNING: CreateLoader - Unsupported protocol(addons) in addons://search/folder.jpg
09:15:24 T:2910553184 NOTICE: Thread Background Loader start, auto delete: false
09:17:15 T:2902164576 NOTICE: Previous line repeats 1 times.
09:17:15 T:2902164576 NOTICE: Thread Jobworker start, auto delete: true
09:17:15 T:2889466976 NOTICE: Thread Background Loader start, auto delete: false
09:17:19 T:2889466976 NOTICE: Previous line repeats 1 times.
09:17:19 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://repos/folder.jpg
09:17:20 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://install/folder.jpg
09:17:20 T:2889466976 WARNING: CreateLoader - Unsupported protocol(addons) in addons://search/folder.jpg
09:17:23 T:2889466976 NOTICE: Thread Background Loader start, auto delete: false
09:17:26 T:2889466976 NOTICE: Previous line repeats 1 times.
09:17:26 T:2889466976 NOTICE: Thread Jobworker start, auto delete: true
09:17:45 T:2952496224 NOTICE: Thread Background Loader start, auto delete: false
09:17:46 T:2952496224 NOTICE: Previous line repeats 1 times.
09:17:46 T:2952496224 WARNING: CreateLoader - Unsupported protocol(addons) in addons://repos/folder.jpg
09:17:46 T:2952496224 WARNING: CreateLoader - Unsupported protocol(addons) in addons://install/folder.jpg
09:17:46 T:2952496224 WARNING: CreateLoader - Unsupported protocol(addons) in addons://search/folder.jpg
09:18:06 T:3043323904 ERROR: Error: Requested setting (videoscreen.screen) was not found. It must be case-sensitive
09:18:44 T:3043323904 ERROR: Previous line repeats 2 times.
09:18:44 T:3043323904 NOTICE: Log level changed to 2
09:18:44 T:3043323904 NOTICE: Enabled debug logging due to GUI setting. Level 2.
09:18:44 T:3043323904 DEBUG: ------ Window Init () ------
09:18:44 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:00
09:18:44 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:18:45 T:2971292768 DEBUG: CecLogMessage - key auto-released: select (0)
09:18:45 T:2971292768 DEBUG: PushCecKeypress - received key b duration 1072
09:18:54 T:2971292768 DEBUG: CecLogMessage - >> 04:44:02
09:18:54 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:18:54 T:2971292768 DEBUG: CecLogMessage - key pressed: down (2)
09:18:54 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 0
09:18:54 T:3043323904 DEBUG: OnKey: 167 (a7) pressed, action is Down
09:18:54 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:02
09:18:54 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:18:55 T:2971292768 DEBUG: CecLogMessage - key auto-released: down (2)
09:18:55 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 1070
09:19:00 T:2971292768 DEBUG: CecLogMessage - >> 04:44:03
09:19:00 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:00 T:2971292768 DEBUG: CecLogMessage - key pressed: left (3)
09:19:00 T:2971292768 DEBUG: PushCecKeypress - received key a9 duration 0
09:19:00 T:3043323904 DEBUG: OnKey: 169 (a9) pressed, action is Left
09:19:01 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:03
09:19:01 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:01 T:2971292768 DEBUG: CecLogMessage - >> 04:44:02
09:19:01 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:01 T:2971292768 DEBUG: CecLogMessage - key released: left (3)
09:19:01 T:2971292768 DEBUG: PushCecKeypress - received key a9 duration 665
09:19:01 T:2971292768 DEBUG: CecLogMessage - key pressed: down (2)
09:19:01 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 0
09:19:01 T:3043323904 DEBUG: OnKey: 167 (a7) pressed, action is Down
09:19:01 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:02
09:19:01 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:02 T:2971292768 DEBUG: CecLogMessage - key auto-released: down (2)
09:19:02 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 1073
09:19:06 T:2971292768 DEBUG: CecLogMessage - >> 04:44:0d
09:19:06 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:06 T:2971292768 DEBUG: CecLogMessage - key pressed: exit (d)
09:19:06 T:2971292768 DEBUG: PushCecKeypress - received key d8 duration 0
09:19:06 T:3043323904 DEBUG: OnKey: menu (d8) pressed, action is PreviousMenu
09:19:06 T:3043323904 DEBUG: CGUIWindowManager:TonguereviousWindow: Deactivate
09:19:06 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:0d
09:19:06 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:06 T:3043323904 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
09:19:06 T:3043323904 DEBUG: CGUIWindowManager:TonguereviousWindow: Activate new
09:19:06 T:3043323904 DEBUG: ------ Window Init (Settings.xml) ------
09:19:06 T:3043323904 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0x0362dee0 dllopen : 1
09:19:06 T:3043323904 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
09:19:06 T:3043323904 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0x035b5310 dllopen : 1
09:19:06 T:3043323904 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
09:19:06 T:3043323904 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
09:19:06 T:3043323904 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(3686400) nBufferAlignmen(16)
09:19:07 T:3043323904 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.resize handle 0x035b5310 dllopen : 1
09:19:07 T:3043323904 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.image_decode handle 0x0362dee0 dllopen : 1
09:19:07 T:2889466976 NOTICE: Thread Jobworker start, auto delete: true
09:19:07 T:2889466976 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0x035b7f48 dllopen : 1
09:19:07 T:2889466976 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
09:19:07 T:2889466976 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0x037a7068 dllopen : 1
09:19:07 T:2889466976 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
09:19:07 T:2889466976 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
09:19:07 T:2889466976 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(2097152) nBufferAlignmen(16)
09:19:07 T:2889466976 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.resize handle 0x037a7068 dllopen : 1
09:19:07 T:2889466976 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.image_decode handle 0x035b7f48 dllopen : 1
09:19:07 T:2889466976 DEBUG: DoWork - took 176 ms to load special://skin/backgrounds/settings.jpg
09:19:07 T:2971292768 DEBUG: CecLogMessage - key auto-released: exit (d)
09:19:07 T:2971292768 DEBUG: PushCecKeypress - received key d8 duration 1207
09:19:11 T:2971292768 DEBUG: CecLogMessage - >> 04:44:0d
09:19:11 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:11 T:2971292768 DEBUG: CecLogMessage - key pressed: exit (d)
09:19:11 T:2971292768 DEBUG: PushCecKeypress - received key d8 duration 0
09:19:11 T:3043323904 DEBUG: OnKey: menu (d8) pressed, action is PreviousMenu
09:19:11 T:3043323904 DEBUG: CGUIWindowManager:TonguereviousWindow: Deactivate
09:19:11 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:0d
09:19:11 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:11 T:3043323904 DEBUG: ------ Window Deinit (Settings.xml) ------
09:19:11 T:3043323904 DEBUG: CGUIWindowManager:TonguereviousWindow: Activate new
09:19:11 T:3043323904 DEBUG: ------ Window Init (Home.xml) ------
09:19:11 T:3043323904 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0x035df058 dllopen : 1
09:19:11 T:3043323904 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
09:19:11 T:3043323904 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0x0384ca40 dllopen : 1
09:19:11 T:3043323904 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
09:19:11 T:3043323904 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
09:19:11 T:3043323904 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(3686400) nBufferAlignmen(16)
09:19:11 T:3043323904 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.resize handle 0x0384ca40 dllopen : 1
09:19:12 T:3043323904 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.image_decode handle 0x035df058 dllopen : 1
09:19:12 T:2971292768 DEBUG: CecLogMessage - key auto-released: exit (d)
09:19:12 T:2971292768 DEBUG: PushCecKeypress - received key d8 duration 1206
09:19:13 T:2971292768 DEBUG: CecLogMessage - >> 04:44:03
09:19:13 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:13 T:2971292768 DEBUG: CecLogMessage - key pressed: left (3)
09:19:13 T:2971292768 DEBUG: PushCecKeypress - received key a9 duration 0
09:19:13 T:3043323904 DEBUG: OnKey: 169 (a9) pressed, action is Left
09:19:13 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:03
09:19:13 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> 04:44:03
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:14 T:2971292768 DEBUG: CecLogMessage - key pressed: left (3)
09:19:14 T:2971292768 DEBUG: PushCecKeypress - received key a9 duration 0
09:19:14 T:3043323904 DEBUG: OnKey: 169 (a9) pressed, action is Left
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:03
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> 04:44:03
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:14 T:2971292768 DEBUG: CecLogMessage - key pressed: left (3)
09:19:14 T:2971292768 DEBUG: PushCecKeypress - received key a9 duration 0
09:19:14 T:3043323904 DEBUG: OnKey: 169 (a9) pressed, action is Left
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:03
09:19:14 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:15 T:2971292768 DEBUG: CecLogMessage - key auto-released: left (3)
09:19:16 T:2971292768 DEBUG: PushCecKeypress - received key a9 duration 1070
09:19:16 T:2971292768 DEBUG: CecLogMessage - >> 04:44:02
09:19:16 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:16 T:2971292768 DEBUG: CecLogMessage - key pressed: down (2)
09:19:16 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 0
09:19:16 T:3043323904 DEBUG: OnKey: 167 (a7) pressed, action is Down
09:19:17 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:02
09:19:17 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:17 T:2971292768 DEBUG: CecLogMessage - key auto-released: down (2)
09:19:17 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 501
09:19:18 T:2971292768 DEBUG: CecLogMessage - >> 04:44:00
09:19:18 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:18 T:2971292768 DEBUG: CecLogMessage - key pressed: select (0)
09:19:18 T:2971292768 DEBUG: PushCecKeypress - received key b duration 0
09:19:18 T:3043323904 DEBUG: OnKey: 11 (0b) pressed, action is Select
09:19:18 T:3043323904 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Videos,Files,return)
09:19:18 T:3043323904 DEBUG: ExecuteXBMCAction : To ActivateWindow(Videos,Files,return)
09:19:18 T:3043323904 DEBUG: Activating window ID: 10025
09:19:18 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:00
09:19:18 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:18 T:3043323904 DEBUG: ------ Window Deinit (Home.xml) ------
09:19:18 T:3043323904 DEBUG: ExecuteXBMCAction : Translating SetProperty(VideosDirectLink,True)
09:19:18 T:3043323904 DEBUG: ExecuteXBMCAction : To SetProperty(VideosDirectLink,True)
09:19:18 T:3043323904 DEBUG: ------ Window Init (MyVideoNav.xml) ------
09:19:18 T:3043323904 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/)
09:19:18 T:3043323904 DEBUG: ParentPath = [sources://video/]
09:19:18 T:2902164576 NOTICE: Thread Background Loader start, auto delete: false
09:19:18 T:3043323904 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0x032468a0 dllopen : 1
09:19:18 T:3043323904 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
09:19:18 T:3043323904 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0x02f355a0 dllopen : 1
09:19:18 T:3043323904 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
09:19:18 T:3043323904 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
09:19:18 T:3043323904 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(3686400) nBufferAlignmen(16)
09:19:18 T:2902164576 DEBUG: Thread Background Loader 2902164576 terminating
09:19:18 T:3043323904 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.resize handle 0x02f355a0 dllopen : 1
09:19:18 T:3043323904 DEBUG: COMXCoreComponent:Big Grineinitialize : OMX.broadcom.image_decode handle 0x032468a0 dllopen : 1
09:19:19 T:2971292768 DEBUG: CecLogMessage - key auto-released: select (0)
09:19:19 T:2971292768 DEBUG: PushCecKeypress - received key b duration 1209
09:19:20 T:2971292768 DEBUG: CecLogMessage - >> 04:44:02
09:19:20 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:20 T:2971292768 DEBUG: CecLogMessage - key pressed: down (2)
09:19:20 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 0
09:19:20 T:3043323904 DEBUG: OnKey: 167 (a7) pressed, action is Down
09:19:20 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:02
09:19:20 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:21 T:2971292768 DEBUG: CecLogMessage - >> 04:44:02
09:19:21 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:21 T:2971292768 DEBUG: CecLogMessage - key pressed: down (2)
09:19:21 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 0
09:19:21 T:3043323904 DEBUG: OnKey: 167 (a7) pressed, action is Down
09:19:21 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:02
09:19:21 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:22 T:2971292768 DEBUG: CecLogMessage - key auto-released: down (2)
09:19:22 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 1079
09:19:25 T:2971292768 DEBUG: CecLogMessage - >> 04:44:02
09:19:25 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:25 T:2971292768 DEBUG: CecLogMessage - key pressed: down (2)
09:19:25 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 0
09:19:25 T:3043323904 DEBUG: OnKey: 167 (a7) pressed, action is Down
09:19:25 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:02
09:19:25 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:26 T:2971292768 DEBUG: CecLogMessage - key auto-released: down (2)
09:19:26 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 1071
09:19:26 T:2971292768 DEBUG: CecLogMessage - >> 04:44:02
09:19:26 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:26 T:2971292768 DEBUG: CecLogMessage - key pressed: down (2)
09:19:26 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 0
09:19:26 T:3043323904 DEBUG: OnKey: 167 (a7) pressed, action is Down
09:19:26 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:02
09:19:26 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:27 T:2971292768 DEBUG: CecLogMessage - >> 04:44:00
09:19:27 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
09:19:27 T:2971292768 DEBUG: CecLogMessage - key released: down (2)
09:19:27 T:2971292768 DEBUG: PushCecKeypress - received key a7 duration 705
09:19:27 T:2971292768 DEBUG: CecLogMessage - key pressed: select (0)
09:19:27 T:2971292768 DEBUG: PushCecKeypress - received key b duration 0
09:19:27 T:3043323904 DEBUG: OnKey: 11 (0b) pressed, action is Select
09:19:27 T:3043323904 DEBUG: CGUIMediaWindow::GetDirectory (smb://LAPTOP/Users/Public/Videos/)
09:19:27 T:3043323904 DEBUG: ParentPath = [sources://video/]
09:19:27 T:2971292768 DEBUG: CecLogMessage - >> 04:8b:00
09:19:27 T:2971292768 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
09:19:27 T:2889466976 DEBUG: OpenDir - Using authentication url smb://john_west1%40btinternet.com:Janeta20120428@LAPTOP/Users/Public/Videos
09:19:27 T:3043323904 DEBUG: ------ Window Init (DialogBusy.xml) ------
09:19:28 T:2971292768 DEBUG: CecLogMessage - key auto-released: select (0)
09:19:28 T:2971292768 DEBUG: PushCecKeypress - received key b duration 1083
09:19:35 T:3043323904 DEBUG: Saving fileitems [smb://LAPTOP/Users/Public/Videos/]
09:19:35 T:3043323904 DEBUG: -- items: 43, sort method: 0, ascending: false
09:19:35 T:3043323904 DEBUG: RunQuery took 7 ms for 0 items query: select * from musicvideoview WHERE c14=4
09:19:35 T:3043323904 DEBUG: RunQuery took 4 ms for 45 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=4
09:19:35 T:2902164576 NOTICE: Thread Background Loader start, auto delete: false
09:19:35 T:2889466976 DEBUG: DoWork - trying to extract thumb from video file smb://LAPTOP/Users/Public/Videos/01 Heart of Glass.m4v
09:19:35 T:2889466976 DEBUG: CSmbFile::Open - opened Users/Public/Videos/01 Heart of Glass.m4v, fd=10000
09:19:35 T:2889466976 DEBUG: DllAvUtilBase: Using libavutil system library
09:19:35 T:2889466976 DEBUG: DllAvCodec: Using libavcodec system library
09:19:35 T:2889466976 DEBUG: DllAvFormat: Using libavformat system library
09:19:35 T:2889466976 DEBUG: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
09:19:35 T:2889466976 DEBUG: Open - avformat_find_stream_info starting
09:19:36 T:3043323904 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
09:19:36 T:2889466976 DEBUG: ffmpeg[AC39C460]: [mov,mp4,m4a,3gp,3g2,mj2] max_analyze_duration 5000000 reached at 5015510
09:19:36 T:2889466976 DEBUG: ffmpeg[AC39C460]: [mov,mp4,m4a,3gp,3g2,mj2] decoding for stream 1 failed
09:19:36 T:2889466976 DEBUG: ffmpeg[AC39C460]: [mov,mp4,m4a,3gp,3g2,mj2] Could not find codec parameters (Video: none (drmi / 0x696D7264), 640x480, 1475 kb/s)

[I've hit the limit on number of lines; will Dropbox all the logs if needed]

Any help appreciated; with thanks in anticipation.
Reply
#2
Do the files have DRM (i.e. downloaded from iTunes?)
Reply
#3
Thanks for the fast response. Yes, all from iTunes. Though they're all from 2009, i.e 2 computers ago. How can I check DRM status?
Reply
#4
You can't play videos from iTunes on any player except Apple's. The files are encrypted.
Reply
#5
Yeah, I've just checked:- 44 total; Playable 29; Unplayable 15 All downloaded with iTunes; the problem doesn't appear to be with the omxplayer
Reply

Logout Mark Read Team Forum Stats Members Help
RPi OpenELEC videos unplayable0