Applying level 3 logs to this problem and some extensive testing:
Logs below from raspbmc latest build.
The following tests are interesting:
IOS7 IPHONE 5S Raspberry connected via via WiFi - OK
IOS7 IPHONE 5S Raspberry connected via via eth0 - FAIL
IOS7 IPAD MINI Raspberry connected via via eth0 - OK
IOS7 IPAD MINI Raspberry connected via via eth0 - OK
So the problems seems to be related to both the Iphone only, and also connecting via eth0 rather than a wifi socket.
This is a long standing problem as far as I am aware with RASPBMC as I have seen this behavior from early last year.
Hopefuly this helps the devs narrow down the issue?
PHP Code:
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers(pipe://2/)
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
16:43:34 T:3036635936 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: OMXPlayer (4)
16:43:34 T:3036635936 DEBUG: CPlayerCoreFactory::GetPlayers: added 3 players
16:43:34 T:3036635936 ERROR: Init: Error creating demuxer
16:43:34 T:3036635936 ERROR: CAudioDecoder: Unable to Init Codec while loading file pipe://2/
16:43:34 T:3036635936 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
16:43:34 T:3036635936 DEBUG: OnQueueNextItem : play state was 1, starting 1
16:43:34 T:3036635936 DEBUG: OnPlayBackStopped : play state was 1, starting 0
16:43:34 T:3036635936 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
16:43:34 T:3036635936 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
16:43:34 T:3036635936 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc
16:43:34 T:3036635936 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged
In contrast this is what happens in playback from an Ipad (which works)
PHP Code:
17:10:31 T:2856600640 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers(pipe://3/)
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
17:10:31 T:3037070112 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: OMXPlayer (4)
17:10:31 T:3037070112 DEBUG: CPlayerCoreFactory::GetPlayers: added 3 players
17:10:31 T:3037070112 DEBUG: FactoryCodec - Audio: passthrough - Opening
17:10:31 T:3037070112 DEBUG: FactoryCodec - Audio: passthrough - Failed
17:10:31 T:3037070112 DEBUG: FactoryCodec - Audio: pcm - Opening
17:10:31 T:3037070112 DEBUG: FactoryCodec - Audio: pcm - Opened
17:10:32 T:3037070112 INFO: AudioDecoder: File is queued
17:10:32 T:3037062208 INFO: CActiveAEResamplePi::CActiveAEResample
17:10:32 T:3037062208 INFO: CActiveAEResamplePi::Init remap:(nil) chan:2->2 rate:44100->44100 format:1->3 bits:1 6->32 norm:1 upmix:0
17:10:32 T:3037070112 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 8 9.000000, Peak 0.000000
17:10:32 T:3037062208 INFO: CActiveAEResamplePi::Init 1.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00
17:10:32 T:3037062208 INFO: CActiveAEResamplePi::Init 0.00 1.00 0.00 0.00 0.00 0.00 0.00 0.00
17:10:33 T:3037062208 INFO: CActiveAEResamplePi::Init 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00
17:10:33 T:3037062208 INFO: Previous line repeats 5 times.
17:10:33 T:3037062208 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 23 1 m_handle 0x2aec3d0
17:10:33 T:3037062208 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_mixer) - port(232) , nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288), nBufferAlignmen(32)
17:10:33 T:3037062208 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.audio_mixer) - port(231 ), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288) nBufferAlignmen(32)
17:10:33 T:3037062208 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
17:10:33 T:3037070112 INFO: PAPlayer::PrepareStream - Ready
17:10:33 T:3037070112 DEBUG: PlayFile : OpenFile succeed, play state 1
17:10:33 T:2789491776 NOTICE: Thread PAPlayer start, auto delete: false
17:10:33 T:2789491776 DEBUG: PAPlayer::Process - Playback started
17:10:33 T:2789491776 DEBUG: OnPlayBackStarted : play state was 1, starting 0
17:10:33 T:3037070112 DEBUG: CGUIInfoManager::SetCurrentSong(pipe://3/)
17:10:33 T:3037070112 DEBUG: LoadMusicTag: loading tag information for file: pipe://3/
17:10:33 T:3037070112 DEBUG: Loading additional tag info for file pipe://3/
17:10:33 T:3037070112 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc