RaspBMC + Video file failures


18 posts
by ahmadali » Fri Sep 14, 2012 7:22 am
Hi All

After getting raspbian installed onto my Pi, I decided to get XBMC working so that I could use the pi as a media centre / server.

My setup is as follows:

Raspberry pi connected to
-HDTV (via HDMI)
-Belkins 2 in 1 USB Powered hub (this also powers the pi)

Into the Belkins hub I have
-Xenta mini keyboard & mouse
-2TB powered USB HDD
-Edimax nano wireless receiver
-USB lead into the pi (one for power, one to the USB ports)

The problem:
After an hour or so I managed to get XBMC installed and using the wifi adapter and got a test video file playing. Feeling somewhat pleased with myself I left the file running and went outside to have a coffee. On my return the video file had frozen.

Initially I didnt think anything of it and simply rebooted XBMC and started the video file again (this time from a different place). Again after about 5 minutes the video crashed.

So... More reading online... following that I overclocked the pi to 850MHZ and tried a different file. This time all seemed fine, except that 15 mins in the video file locked up.

I've tried to register on the Raspbmc forum but they appear to be having registration isssues at the moment (getting a BB error).

Anyone have any ideas?

Thanks in advance!
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by JoeDaStudd » Fri Sep 14, 2012 8:19 am
Do you have the RasPi in a case?
If so try removing it out of the case and see if that fixes the issue.
Mine overheated when put into a prototype case I designed due to lack of airflow, it would freeze up within a few minutes when put under and stress (eg xbmc playing a video).

If its not the case then I would systematically remove the peripherals one at a time as it might be one of them drawing too much power.
Posts: 271
Joined: Fri Jan 27, 2012 1:34 pm
by ahmadali » Fri Sep 14, 2012 9:33 am
I removed the Pi from the case and turned on debugging in XBMC.

I'm still getting the same issue. From the debugging information on screen, when the video is running the CPU usage is ~50%-60%. Where the video freezes, its switches between CPU usage of ~8% to 9%.

Have tried unplugging various USB devices. Will try using the RS components adapter as well now but not holding out much hope (the adapter wasnt enought power previously).
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by rey » Fri Sep 14, 2012 10:21 am
It doesn't sound like you're running Raspbmc, it sounds like you're running Raspian with XBMC installed. The two are entirely seperate.
Posts: 38
Joined: Fri May 04, 2012 8:01 am
by ahmadali » Fri Sep 14, 2012 10:27 am
Nope, Definitely running RaspBMC (installed onto an SD card using the installer)
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by ahmadali » Fri Sep 14, 2012 10:57 am
Debugging:
Code: Select all
10:44:48 T:1103978496  NOTICE: Enabled debug logging due to GUI setting. Level 2.
10:44:48 T:1103978496   DEBUG: ------ Window Init () ------
10:44:52 T:1103978496   DEBUG: ProcessMouse: trying mouse action leftclick
10:44:52 T:1103978496   DEBUG: ExecuteXBMCAction : Translating ActivateWindow(home)
10:44:52 T:1103978496   DEBUG: ExecuteXBMCAction : To ActivateWindow(home)
10:44:52 T:1103978496   DEBUG: Activating window ID: 10000
10:44:52 T:1103978496   DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
10:44:52 T:1103978496   DEBUG: ------ Window Init (Home.xml) ------
10:44:52 T:1103978496    INFO: Loading skin file: Home.xml
10:44:53 T:1103978496   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
10:44:53 T:1103978496   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
10:44:53 T:1103978496   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
10:44:53 T:1103978496   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(8355840) nBufferAlignmen(16)
10:44:55 T:1165870144  NOTICE: Thread Jobworker start, auto delete: true
10:44:55 T:1165870144   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
10:44:55 T:1165870144   DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/ImageLib-arm.so
10:44:55 T:1165870144   DEBUG: DoWork - took 112 ms to load special://masterprofile/Thumbnails/7/7f8bcb0c.png
10:45:10 T:1103978496   DEBUG: ProcessMouse: trying mouse action leftclick
10:45:10 T:1103978496   DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Videos,Files,return)
10:45:10 T:1103978496   DEBUG: ExecuteXBMCAction : To ActivateWindow(Videos,Files,return)
10:45:10 T:1103978496   DEBUG: Activating window ID: 10025
10:45:11 T:1103978496   DEBUG: ------ Window Deinit (Home.xml) ------
10:45:11 T:1103978496   DEBUG: ExecuteXBMCAction : Translating SetProperty(VideosDirectLink,True)
10:45:11 T:1103978496   DEBUG: ExecuteXBMCAction : To SetProperty(VideosDirectLink,True)
10:45:11 T:1103978496   DEBUG: ------ Window Init (MyVideoNav.xml) ------
10:45:11 T:1103978496    INFO: Loading skin file: MyVideoNav.xml
10:45:12 T:1103978496   DEBUG: CGUIMediaWindow::GetDirectory (/media/usb0/)
10:45:12 T:1103978496   DEBUG:   ParentPath = [/media/usb0/]
10:45:12 T:1103978496   DEBUG: RunQuery took 3 ms for 2 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=2
10:45:12 T:1191920704  NOTICE: Thread Background Loader start, auto delete: false
10:45:12 T:1103978496   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
10:45:12 T:1103978496   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
10:45:12 T:1103978496   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
10:45:12 T:1103978496   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(8355840) nBufferAlignmen(16)
10:45:12 T:1165870144   DEBUG: DoWork - trying to extract filestream details from video file /media/usb0/Test.avi
10:45:12 T:1165870144   DEBUG: DoWork - trying to extract filestream details from video file /media/usb0/The Matrix (1999)  [ENG] [DVDrip] CD1.avi
10:45:12 T:1191920704   DEBUG: Thread Background Loader 1191920704 terminating
10:45:12 T:1165870144   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
10:45:12 T:1165870144   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
10:45:12 T:1165870144   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
10:45:12 T:1165870144   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(235520) nBufferAlignmen(16)
10:45:15 T:1165870144   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
10:45:15 T:1165870144   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
10:45:15 T:1165870144   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
10:45:15 T:1165870144   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(235520) nBufferAlignmen(16)
10:45:17 T:1103978496   DEBUG: ProcessMouse: trying mouse action rightclick
10:45:17 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers(/media/usb0/Test.avi)
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
10:45:17 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
10:45:17 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
10:45:17 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
10:45:17 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
10:45:17 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
10:45:17 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
10:45:17 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
10:45:17 T:1103978496   DEBUG: CFavourites::Load - no system favourites found, skipping
10:45:17 T:1103978496   DEBUG: CFavourites::Load - no userdata favourites found, skipping
10:45:17 T:1103978496    INFO: Loading skin file: DialogContextMenu.xml
10:45:17 T:1103978496   DEBUG: ------ Window Init (DialogContextMenu.xml) ------
10:45:23 T:1103978496   DEBUG: ProcessMouse: trying mouse action leftclick
10:45:23 T:1103978496   DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
10:45:23 T:1103978496   DEBUG: OnPlayMedia /media/usb0/Test.avi
10:45:23 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers(/media/usb0/Test.avi)
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
10:45:23 T:1103978496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
10:45:23 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
10:45:23 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
10:45:23 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
10:45:23 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
10:45:23 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
10:45:23 T:1103978496   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
10:45:23 T:1103978496  NOTICE: COMXPlayer: Opening: /media/usb0/Test.avi
10:45:23 T:1103978496 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
10:45:23 T:1103978496   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
10:45:23 T:1103978496   DEBUG: LinuxRendererGL: Cleaning up GL resources
10:45:23 T:1191920704  NOTICE: Thread COMXPlayer start, auto delete: false
10:45:23 T:1191920704   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81
10:45:23 T:1191920704  NOTICE: OMXClock using video as reference
10:45:23 T:1191920704   DEBUG: OMXClock::OMXStart
10:45:23 T:1191920704  NOTICE: Creating InputStream
10:45:23 T:1191920704   DEBUG: ScanForExternalSubtitles: Searching for subtitles...
10:45:23 T:1191920704   DEBUG: ScanForExternalSubtitles: END (total time: 37 ms)
10:45:23 T:1191920704   DEBUG: OMXClock::OMXReset 0x00000003
10:45:23 T:1191920704  NOTICE: OMXClock using video as reference
10:45:23 T:1191920704   ERROR: COMXCoreComponent::SetConfig - OMX.broadcom.clock failed with omx_err(0x80001005)
10:45:23 T:1191920704   ERROR: OMXClock::OMXReset error setting OMX_IndexConfigTimeClockState
10:45:23 T:1191920704  NOTICE: Creating Demuxer
10:45:23 T:1191920704   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-53-arm.so)
10:45:23 T:1191920704   DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avcodec-53-arm.so
10:45:23 T:1191920704   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-53-arm.so)
10:45:23 T:1191920704   DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avformat-53-arm.so
10:45:23 T:1103978496   DEBUG: ------ Window Init (DialogBusy.xml) ------
10:45:23 T:1191920704   DEBUG: Open - probing detected format [avi]
10:45:25 T:1191920704   DEBUG: Open - avformat_find_stream_info starting
10:45:25 T:1191920704   DEBUG: Open - av_find_stream_info finished
10:45:25 T:1191920704    INFO: ffmpeg[470B4440]: Input #0, avi, from '/media/usb0/Test.avi':
10:45:25 T:1191920704    INFO: ffmpeg[470B4440]:   Metadata:
10:45:25 T:1191920704    INFO: ffmpeg[470B4440]:     encoder         : FairUse Wizard - http://fairusewizard.com
10:45:25 T:1191920704    INFO: ffmpeg[470B4440]:   Duration: 01:37:53.24, start: 0.000000, bitrate: 1040 kb/s
10:45:25 T:1191920704    INFO: ffmpeg[470B4440]:     Stream #0:0: Video: mpeg4 (Simple Profile) (XVID / 0x44495658), yuv420p, 672x272 [SAR 1:1 DAR 42:17], 23.98 tbr, 23.98 tbn, 23.98 tbc
10:45:25 T:1191920704    INFO: ffmpeg[470B4440]:     Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16, 128 kb/s
10:45:25 T:1191920704  NOTICE: Opening video stream: 0 source: 256
10:45:25 T:1191920704   DEBUG: OMXClock::OMXStop
10:45:25 T:1191920704   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131
10:45:25 T:1191920704   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90
10:45:25 T:1191920704   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11
10:45:25 T:1191920704   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(60), nBufferSize(81920), nBufferAlignmen(16)
10:45:25 T:1191920704   DEBUG: COMXVideo::Open - decoder_component(0x0x15d6f28), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 1
10:45:25 T:1191920704    INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-mpeg4 width 672 height 272 profile 0 fps 23.976025
10:45:25 T:1191920704    INFO: OMXPlayerVideo::OpenDecoder fps: 23.976025 hdmi_ntsc_freqs 1
10:45:25 T:1191920704   DEBUG: OMXClock::OMXReset 0x00000003
10:45:25 T:1191920704  NOTICE: OMXClock using video as reference
10:45:25 T:1191920704  NOTICE: Creating video thread
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed fake 1
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed real 1
10:45:25 T:1303102528  NOTICE: Thread COMXPlayerVideo start, auto delete: false
10:45:25 T:1191920704  NOTICE: Opening audio stream: 1 source: 256
10:45:25 T:1303102528   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:45:25 T:1191920704  NOTICE: Creating audio thread
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed fake 1
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed real 1
10:45:25 T:1257677888  NOTICE: Thread COMXPlayerAudio start, auto delete: false
10:45:25 T:1191920704   DEBUG: OMXClock::OMXStop
10:45:25 T:1191920704   DEBUG: OMXClock::OMXReset 0x00000003
10:45:25 T:1191920704  NOTICE: OMXClock using video as reference
10:45:25 T:1191920704   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /media/usb0/Test.avi
10:45:25 T:1191920704   DEBUG: Process - Start position set to last stopped position: 2277000
10:45:25 T:1191920704   DEBUG: SeekTime - unknown position after seek
10:45:25 T:1191920704   DEBUG: Process - starting demuxer from: 2277000
10:45:25 T:1191920704   DEBUG: COMXPlayer::SetCaching - caching state 2
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed fake 0
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed real 0
10:45:25 T:1257677888   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED
10:45:25 T:1257677888   DEBUG: COMXPlayerAudio - CDVDMsg::GENERAL_RESYNC(2286552000.000000, 1)
10:45:25 T:1257677888   DEBUG: OMXClock::OMXStop
10:45:25 T:1257677888   DEBUG: OMXClock::OMXReset 0x00000003
10:45:25 T:1257677888  NOTICE: OMXClock using video as reference
10:45:25 T:1257677888   DEBUG: OMXClock::OMXStop
10:45:25 T:1257677888   DEBUG: COMXAudio::SetCodingType OMX_AUDIO_CodingPCM
10:45:25 T:1257677888    INFO: ==[Downmix Matrix]==
10:45:25 T:1257677888    INFO: FL = FL(1)
10:45:25 T:1257677888    INFO: FR = FR(1)
10:45:25 T:1257677888    INFO: ====================
10:45:25 T:1257677888   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100
10:45:25 T:1257677888   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_decode input port 120 output port 121
10:45:25 T:1257677888   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231
10:45:25 T:1257677888   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_decode) - port(120), nBufferCountMin(4), nBufferCountActual(62), nBufferSize(6144), nBufferAlignmen(16)
10:45:25 T:1257677888   DEBUG: COMXAudio::Initialize Ouput bps 16 samplerate 48000 channels 2 buffer size 384000 bytes per second 192000
10:45:25 T:1257677888   DEBUG: COMXAudio::Initialize Input bps 16 samplerate 48000 channels 2 buffer size 384000 bytes per second 192000
10:45:25 T:1257677888   DEBUG: COMXAudio::Initialize device hdmi passthrough 0 hwdecode 0 external clock 1
10:45:25 T:1257677888    INFO: Audio codec  channels 2 samplerate 48000 bitspersample 16
10:45:25 T:1257677888   DEBUG: OMXClock::OMXReset 0x00000003
10:45:25 T:1257677888  NOTICE: OMXClock using audio as reference
10:45:25 T:1257677888   DEBUG: ADec : setStartTime 2286.552002
10:45:25 T:1303102528   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_RESYNC(2286575958.333333, 0)
10:45:25 T:1303102528    INFO: COMXPlayerVideo - Stillframe left, switching to normal playback
10:45:25 T:1303102528   DEBUG: VDec : setStartTime 2286.575928
10:45:25 T:1303102528   DEBUG: Output - change configuration. 1920x1080. framerate: 23.98. format: BYPASS
10:45:25 T:1257677888   DEBUG: pcm->direction      : input
10:45:25 T:1257677888   DEBUG: pcm->nPortIndex     : 232
10:45:25 T:1257677888   DEBUG: pcm->eNumData       : 0
10:45:25 T:1257677888   DEBUG: pcm->eEndian        : 1
10:45:25 T:1257677888   DEBUG: pcm->bInterleaved   : 1
10:45:25 T:1257677888   DEBUG: pcm->nBitPerSample  : 16
10:45:25 T:1257677888   DEBUG: pcm->ePCMMode       : 0
10:45:25 T:1257677888   DEBUG: pcm->nChannels      : 2
10:45:25 T:1257677888   DEBUG: pcm->nSamplingRate  : 48000
10:45:25 T:1257677888   DEBUG: OMX_AUDIO_ChannelLF
10:45:25 T:1257677888   DEBUG: OMX_AUDIO_ChannelRF
10:45:25 T:1257677888   DEBUG: pcm->direction      : output
10:45:25 T:1257677888   DEBUG: pcm->nPortIndex     : 100
10:45:25 T:1257677888   DEBUG: pcm->eNumData       : 0
10:45:25 T:1257677888   DEBUG: pcm->eEndian        : 1
10:45:25 T:1257677888   DEBUG: pcm->bInterleaved   : 1
10:45:25 T:1257677888   DEBUG: pcm->nBitPerSample  : 16
10:45:25 T:1257677888   DEBUG: pcm->ePCMMode       : 0
10:45:25 T:1257677888   DEBUG: pcm->nChannels      : 2
10:45:25 T:1257677888   DEBUG: pcm->nSamplingRate  : 48000
10:45:25 T:1257677888   DEBUG: OMX_AUDIO_ChannelLF
10:45:25 T:1257677888   DEBUG: OMX_AUDIO_ChannelRF
10:45:25 T:1191920704   DEBUG: COMXPlayer::HandleMessages - player started 1
10:45:25 T:1303102528 WARNING: CRenderManager::Configure - timeout waiting for previous frame
10:45:25 T:1303102528   DEBUG: Resolution 1920x1080 @ 50.00i - Full Screen (25) not a very good match for fps 23.976 (weight: 0.043), trying 2:3 cadence
10:45:25 T:1303102528  NOTICE: Display resolution ADJUST : 1920x1080 @ 60.00i - Full Screen (16) (weight: 0.001)
10:45:25 T:1103978496   DEBUG: CGUIInfoManager::SetCurrentMovie(/media/usb0/Test.avi)
10:45:25 T:1103978496   DEBUG: GetMovieId (/media/usb0/Test.avi), query = select idMovie from movie where idFile=3
10:45:25 T:1103978496   DEBUG: GetEpisodeId (/media/usb0/Test.avi), query = select idEpisode from episode where idFile=3
10:45:25 T:1103978496   DEBUG: GetMusicVideoId (/media/usb0/Test.avi), query = select idMVideo from musicvideo where idFile=3
10:45:25 T:1191920704   DEBUG: COMXPlayer::SetCaching - caching state 0
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed fake 1
10:45:25 T:1191920704   DEBUG: OMXClock::OMXSetSpeed real 1
10:45:25 T:1257677888   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED
10:45:25 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStart
10:45:25 T:1103978496   DEBUG: GetMovieId (/media/usb0/Test.avi), query = select idMovie from movie where idFile=3
10:45:26 T:1103978496   DEBUG: GetEpisodeId (/media/usb0/Test.avi), query = select idEpisode from episode where idFile=3
10:45:26 T:1103978496   DEBUG: GetMusicVideoId (/media/usb0/Test.avi), query = select idMVideo from musicvideo where idFile=3
10:45:26 T:1103978496   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
10:45:26 T:1103978496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
10:45:26 T:1103978496   DEBUG: Activating window ID: 12005
10:45:26 T:1103978496   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
10:45:26 T:1103978496   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
10:45:26 T:1103978496    INFO: Loading skin file: VideoFullScreen.xml
10:45:26 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStop
10:45:26 T:1257677888   DEBUG: OMXClock::OMXSetSpeed fake 1
10:45:26 T:1257677888   DEBUG: OMXClock::OMXSetSpeed real 1
10:45:26 T:1257677888    INFO: COMXPlayerAudio - Switching to normal playback
10:45:26 T:1303102528 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete
10:45:26 T:1191920704   DEBUG: COMXPlayer::HandleMessages - player started 2
10:45:26 T:1303102528 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame
10:45:27 T:1103978496  NOTICE: Using GL_TEXTURE_2D
10:45:27 T:1103978496   DEBUG: GL: Requested render method: 0
10:45:27 T:1103978496  NOTICE: GL: Using BYPASS render method
10:45:27 T:1103978496  NOTICE: GL: NPOT texture support detected
10:45:27 T:1103978496   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
10:45:27 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStart
10:45:27 T:1103978496   DEBUG: dest_rect.x_offset 61 dest_rect.y_offset 31 dest_rect.width 1800 dest_rect.height 1013
10:45:28 T:1103978496   DEBUG: ------ Window Deinit (Pointer.xml) ------
10:45:28 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStop
10:45:28 T:1257677888   DEBUG: OMXClock::OMXSetSpeed fake 1
10:45:28 T:1257677888   DEBUG: OMXClock::OMXSetSpeed real 1
10:45:28 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStart
10:45:28 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStop
10:45:28 T:1257677888   DEBUG: OMXClock::OMXSetSpeed fake 1
10:45:28 T:1257677888   DEBUG: OMXClock::OMXSetSpeed real 1
10:45:28 T:1257677888    INFO: COMXPlayerAudio - Switching to normal playback
10:45:45 T:1165870144   DEBUG: Thread Jobworker 1165870144 terminating (autodelete)
10:47:01 T:1303102528    INFO: COMXPlayerVideo - Stillframe detected, switching to forced 23.976025 fps
10:47:22 T:1191920704   DEBUG: COMXPlayer::SetCaching - caching state 1
10:47:22 T:1191920704   DEBUG: OMXClock::OMXSetSpeed fake 0
10:47:22 T:1191920704   DEBUG: OMXClock::OMXSetSpeed real 0
10:47:22 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStart
10:47:22 T:1191920704   DEBUG: COMXPlayer::HandleMessages - player started 2
10:47:22 T:1257677888   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED
10:47:22 T:1191920704   DEBUG: COMXPlayer::HandleMessages - player started 1
10:47:22 T:1103978496   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
10:47:22 T:1191920704   DEBUG: COMXPlayer::SetCaching - caching state 3
10:47:22 T:1191920704   DEBUG: OMXClock::OMXSetSpeed fake 1
10:47:22 T:1257677888   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED
10:47:22 T:1303102528    INFO: COMXPlayerVideo - Stillframe left, switching to normal playback
10:47:23 T:1303102528 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete
10:47:23 T:1103978496   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
10:47:24 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStop
10:47:24 T:1257677888   DEBUG: OMXClock::OMXSetSpeed fake 1
10:47:24 T:1257677888   DEBUG: OMXClock::OMXSetSpeed real 1
10:47:24 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStart
10:47:26 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStop
10:47:26 T:1257677888   DEBUG: OMXClock::OMXSetSpeed fake 1
10:47:26 T:1257677888   DEBUG: OMXClock::OMXSetSpeed real 1
10:47:26 T:1257677888   DEBUG: OMXClock::OMXAudioBufferStart


dmesg:
Code: Select all
[ 2364.729310] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2364.729354] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2366.192343] ###=> usb_read_port_complete => urb status(-71)
[ 2387.528062] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2390.338178] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2390.338485] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2390.718579] ###=> usb_read_port_complete => urb status(-71)
[ 2390.738340] rtl8192c_dm_RF_Saving(): RF_Save
[ 2392.728389] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2392.730139] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2392.730181] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2417.286713] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2417.286993] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2418.729738] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2418.729777] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2420.358855] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2420.359189] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2420.734472] rtl8192c_dm_RF_Saving(): RF_Save
[ 2422.480372] reg 0x4c, usb write 1 fail, status:-71 value=0x8, vendorreq_times:1
[ 2422.730002] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2422.731470] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2422.731507] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2428.310714] usb 1-1.2.4.4: reset high speed USB device number 9 using dwc_otg
[ 2428.730703] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2428.731068] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2430.731004] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2430.731099] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2434.730907] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2434.731138] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2436.731044] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2436.731094] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2438.731203] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2438.731417] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2438.740266] rtl8192c_dm_RF_Saving(): RF_Save
[ 2442.731444] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2442.733028] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2442.733065] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2450.351874] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2450.352104] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2450.736796] rtl8192c_dm_RF_Saving(): RF_Save
[ 2452.732080] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2452.733482] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2452.733507] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2457.982770] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2480.368223] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2480.368519] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2480.739251] rtl8192c_dm_RF_Saving(): RF_Save
[ 2481.707003] reg 0xe18, usb write 4 fail, status:-71 value=0x32333435, vendorreq_times:1
[ 2482.106962] reg 0xc9a, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2482.508211] reg 0x83c, usb write 4 fail, status:-71 value=0xb0c0d0e, vendorreq_times:1
[ 2482.515687] survey done event(10)
[ 2482.734337] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2482.736660] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2482.736694] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2510.355998] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2510.356360] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2510.740950] rtl8192c_dm_RF_Saving(): RF_Save
[ 2512.736111] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2512.737619] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2512.737644] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2518.737862] reg 0x46, usb write 1 fail, status:-71 value=0xfb, vendorreq_times:1
[ 2538.778390] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2540.377384] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2540.377666] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2540.742901] rtl8192c_dm_RF_Saving(): RF_Save
[ 2542.738186] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2542.739518] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2542.739542] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2544.756214] ###=> usb_read_port_complete => urb status(-71)
[ 2548.739863] reg 0x44, usb write 1 fail, status:-71 value=0xfb, vendorreq_times:1
[ 2566.460230] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2570.370106] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2570.370373] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2570.896220] rtl8192c_dm_RF_Saving(): RF_Save
[ 2572.740270] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2572.741719] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2572.741742] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2574.161258] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2581.541174] reg 0x4c, usb write 1 fail, status:-71 value=0x8, vendorreq_times:1
[ 2600.389312] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2600.389564] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2600.747425] rtl8192c_dm_RF_Saving(): RF_Save
[ 2602.518484] survey done event(d)
[ 2602.742461] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2602.743798] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2602.743829] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2617.603479] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2617.603773] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2618.743509] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2618.743557] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2630.391225] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2630.391518] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2630.752548] rtl8192c_dm_RF_Saving(): RF_Save
[ 2632.744560] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2632.745996] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2632.746020] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2657.601084] ###=> usb_read_port_complete => urb status(-71)
[ 2660.293447] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2660.293848] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2660.546734] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2661.011455] rtl8192c_dm_RF_Saving(): RF_Save
[ 2662.746752] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2662.748080] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2662.748112] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2670.747243] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2670.747482] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2672.167989] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2672.747383] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2672.747431] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2680.747918] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2680.748202] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2682.748177] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2682.748225] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2686.748441] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2686.748652] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2687.629719] ###=> usb_read_port_complete => urb status(-71)
[ 2688.748569] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2688.748615] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2690.749266] reg 0x46, usb write 1 fail, status:-71 value=0xff, vendorreq_times:1
[ 2694.589263] reg 0x4c, usb write 1 fail, status:-71 value=0x8, vendorreq_times:1
[ 2720.405153] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2720.405495] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2720.918338] reg 0xa2c, usb write 4 fail, status:-71 value=0xd30000, vendorreq_times:1
[ 2720.922347] rtl8192c_dm_RF_Saving(): RF_Save
[ 2722.524081] reg 0xe18, usb write 4 fail, status:-71 value=0x32333435, vendorreq_times:1
[ 2722.527793] survey done event(a)
[ 2722.751035] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2722.752352] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2722.752374] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2750.392973] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2750.393324] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2750.757932] rtl8192c_dm_RF_Saving(): RF_Save
[ 2752.753115] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2752.754679] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2752.754715] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2755.473714] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2768.274467] reg 0x4c, usb write 1 fail, status:-71 value=0x8, vendorreq_times:1
[ 2780.312013] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2780.312285] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2780.759891] rtl8192c_dm_RF_Saving(): RF_Save
[ 2782.755305] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2782.756631] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2782.756653] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2793.616480] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2793.616855] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2794.756076] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2794.756124] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2803.045862] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2803.046083] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2804.761820] rtl8192c_dm_RF_Saving(): RF_Save
[ 2806.756614] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2806.757943] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2806.757975] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2808.056685] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2808.056969] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2808.763826] rtl8192c_dm_RF_Saving(): RF_Save
[ 2810.757183] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2810.760154] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2810.760263] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2829.618120] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2840.321252] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2840.321562] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2840.762908] rtl8192c_dm_RF_Saving(): RF_Save
[ 2842.534472] survey done event(e)
[ 2842.758590] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2842.760165] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2842.760210] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2862.760574] reg 0x46, usb write 1 fail, status:-71 value=0xfb, vendorreq_times:1
[ 2863.619602] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2863.619931] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2864.759651] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2864.759698] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2867.300078] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2874.410471] reg 0x4c, usb write 1 fail, status:-71 value=0x0, vendorreq_times:1
[ 2888.761674] reg 0x44, usb write 1 fail, status:-71 value=0xfb, vendorreq_times:1
[ 2900.330448] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2900.330700] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2900.766566] rtl8192c_dm_RF_Saving(): RF_Save
[ 2902.761741] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2902.763058] rtl8192c_dm_RF_Saving(): RF_Normal
[ 2902.763079] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2921.262675] reg 0x46, usb read 1 fail, status:-110 value=0x0, vendorreq_times:1
[ 2927.622895] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2927.623152] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2929.262847] reg 0x210, usb read 4 fail, status:-110 value=0x0, vendorreq_times:1
[ 2929.263162] rtw_set_ps_mode(): Enter 802.11 power save mode...
[ 2929.263191] rtl8192c_set_FwPwrMode_cmd(): Mode = 1, SmartPS = 2
[ 2940.268661] rtw_set_ps_mode(): Busy Traffic , Leave 802.11 power save..
[ 2940.268930] rtl8192c_set_FwPwrMode_cmd(): Mode = 0, SmartPS = 0
[ 2940.767660] rtl8192c_dm_RF_Saving(): RF_Save
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by ahmadali » Fri Sep 14, 2012 1:26 pm
Okay... So further update. After going out to buy a multimeter and ipad 10w powersupply I can confirm that the pi running completly headless (no HDMI or USB connections) currently only gets between 4.78 and 4.81V across TP1 and TP2.

Further update. On removing the SD card as well. I get 5V. Is this a broken SD Card interface?

Do I just have a dud pi?
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by ahmadali » Sat Sep 15, 2012 8:54 pm
Hi Guys,

Anybody have any ideas?
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by dom » Sun Sep 16, 2012 9:45 am
I'd suspect the wifi. Can you connect through wired ethernet and repeat the test?
Or copy the file onto the sdcard and try that?
Raspberry Pi Engineer
Raspberry Pi Engineer
Posts: 3864
Joined: Wed Aug 17, 2011 7:41 pm
Location: Cambridge
by ahmadali » Sun Sep 16, 2012 10:40 am
Hi

I tested as follows:

Raspberry Pi connected to power supply only - 5.0 to 5.1V
Raspberry Pi connected to power supply and SD card - 4.79-4.8V
Raspberry Pi connected to power supply and SD card and HDMI and USB Peripherals 4.78-4.8V

Seems like the SD Card to me unless someone else has any ideas?

Cheers!
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by dom » Sun Sep 16, 2012 11:22 am
ahmadali wrote:Raspberry Pi connected to power supply only - 5.0 to 5.1V
Raspberry Pi connected to power supply and SD card - 4.79-4.8V
Raspberry Pi connected to power supply and SD card and HDMI and USB Peripherals 4.78-4.8V

Seems like the SD Card to me unless someone else has any ideas?


No the sdcard isn't consuming the power. It is just without out, the board doesn't boot, so the ARM isn't powered on, therefore not much power is required.
Your power supply is dropping voltage when more current is consumed. This is quite normal (but higher quality supplies will drop less).
The voltage you have got is just about within range, although I wouldn't be suprised if it drops more when xbmc is up and running.

Powering the Pi from a USB hub is questionable (although it can be fine). I'm not sure if they guarantee mroe than 500mA there.
Raspberry Pi Engineer
Raspberry Pi Engineer
Posts: 3864
Joined: Wed Aug 17, 2011 7:41 pm
Location: Cambridge
by ahmadali » Sun Sep 16, 2012 11:39 am
Hi Dom

Thanks for the further insight. So.. I have tried connecting the following to power the Raspberry Pi:

RS Components 5V 1A power supply
Generic 5V 1A power supply
HTC 5V 1.2A power supply
Apple 5V 2A power supply
USB Hub power.

I repeated the test and the results didn't change. Think its worthwhile sending back the PI for a replacement / repair?
Last edited by ahmadali on Sun Sep 16, 2012 11:42 am, edited 1 time in total.
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by dom » Sun Sep 16, 2012 11:41 am
ahmadali wrote:I repeated the test and the results didn't change. Think its worthwhile sending back the PI for a replacement / repair?


I doubt that will change anything. Do you know anyone else with a Pi that can see if swapping it fixes it?
I'm suspecting the wifi. Have you tried playing a video through wired network or from sdcard?
Raspberry Pi Engineer
Raspberry Pi Engineer
Posts: 3864
Joined: Wed Aug 17, 2011 7:41 pm
Location: Cambridge
by ahmadali » Sun Sep 16, 2012 11:44 am
I've tried using the SD card as well as the USB HDD with the wifi dongle / Ethernet cable / keyboard disconnected, same results...
Posts: 12
Joined: Fri Aug 31, 2012 8:23 am
by exwaver » Mon Sep 17, 2012 7:13 am
I have exactly the same problem with Raspbian and xbian and tried every different way possible but still it freezes, so I have given up trying to get it to work. If someone does get it working 100%, then I would be interested in having another bash at it.
Posts: 23
Joined: Thu Jul 05, 2012 10:34 am
by AJ123 » Tue Nov 06, 2012 10:15 am
I have the same problem pi is running off a 5v black berry charger.
It boots of a 16GB SD card with a usb powered hub connected which can also power the pi.
My 1TB usb hard drive is connected via a Y cable one end in an iphone usb plug charger and the the other end in the hub (which goes into the pi).

Drives are mounted fine, xbmc picks up my hard drive and all the files on it but plays for about 2min then freezes.

This is so annoying and frustrating because i am seeing all sorts of post about the pi playing 1080p video files fine. Mine is no where close to playing even a few seconds of steady playback with out it freezing.

I have even converted one of my movies to MPEG4 ACC 720p the format which the pi can handle and still the same problem.

Really hope someone knows what is going on. :cry:
Posts: 7
Joined: Tue Nov 06, 2012 9:13 am
by Paul Webster » Tue Nov 06, 2012 12:12 pm
Try copying a sample file to the SD card and then disconnecting USB attached drive - if it then plays it shows that it is not the file that is the problem.
User avatar
Posts: 323
Joined: Sat Jul 30, 2011 4:49 am
Location: London, UK
by AJ123 » Tue Nov 06, 2012 12:18 pm
Ok i will try this later on tonight.

I have just read a post on the internet about this hub:
http://www.amazon.co.uk/Dynamode-7-port ... roduct_top

This is the hub i am using with my Pi and I think this may be the cause, I wil try transferring a file over to the SD card first then connect the drive directly to the pi and see if it works.

Thanks for your reply.
Posts: 7
Joined: Tue Nov 06, 2012 9:13 am