14:45:38 T:3041431552 INFO: easy_aquire - Created session to http://dyndns.dyndns.com 14:45:38 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:38 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:38 T:3041431552 DEBUG: CCurlFile::GetMimeType - http://dyndns.dyndns.com:5555/videostream.asf?user=xx&pwd=xxx&resolution=8 -> video/x-ms-asf 14:45:38 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:38 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:38 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:38 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:39 T:3041431552 DEBUG: CCurlFile::GetMimeType - http://dyndns.dyndns.com:5555/videostream.asf?user=xx&pwd=xxx&resolution=8 -> video/x-ms-asf 14:45:39 T:3041431552 DEBUG: CurlFile::Open(0xa83efc00) http://dyndns.dyndns.com:5555/videostream.asf?user=xx&pwd=xxx&resolution=8 14:45:39 T:3041431552 NOTICE: Parsing ASX 14:45:39 T:3041431552 ERROR: Unable to parse ASX info Error: Error document empty. 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers(http://dyndns.dyndns.com:5555/videostream.asf?user=xx&pwd=xxx&resolution=8) 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: sdp/asf 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: adding player: DVDPlayer (1) for rule: sdp/asf 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 14:45:39 T:3041431552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers: matched 1 rules with players 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (4) 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: OMXPlayer (4) 14:45:39 T:3041431552 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 14:45:39 T:3041431552 INFO: Created player OMXPlayer for core 1 / OMXPlayer forced as DVDPlayer 14:45:39 T:3041431552 NOTICE: COMXPlayer: Opening: http://dyndns.dyndns.com:5555/videostream.asf?user=xx&pwd=xxx&resolution=8 14:45:39 T:3041431552 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.clock handle 0xa8367fc0 dllopen : 1 14:45:39 T:3041431552 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81 14:45:39 T:3041431552 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 14:45:39 T:3041431552 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 14:45:39 T:3041431552 DEBUG: LinuxRendererGL: Cleaning up GL resources 14:45:39 T:3041431552 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-51-arm.so) 14:45:39 T:3041431552 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avutil-51-arm.so 14:45:39 T:3041431552 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-2-arm.so) 14:45:39 T:3041431552 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/swscale-2-arm.so 14:45:39 T:2702730304 NOTICE: Thread COMXPlayer start, auto delete: false 14:45:39 T:2702730304 NOTICE: Creating InputStream 14:45:39 T:2702730304 DEBUG: CFileCache::Open - opening using cache 14:45:39 T:2702730304 DEBUG: CurlFile::Open(0x27f09f0) http://dyndns.dyndns.com:5555/videostream.asf?user=xx&pwd=xxx&resolution=8 14:45:39 T:3041431552 DEBUG: ------ Window Init (DialogBusy.xml) ------ 14:45:39 T:3041431552 DEBUG: Keyboard: scancode: 1c, sym: 000d, unicode: 0000, modifier: 0 14:45:39 T:3041431552 DEBUG: OnKey: return (f00d) pressed, action is Select 14:45:39 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:39 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:39 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:39 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:39 T:2702730304 NOTICE: Creating Demuxer 14:45:39 T:2702730304 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-53-arm.so) 14:45:39 T:2702730304 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avcodec-53-arm.so 14:45:40 T:2874143808 NOTICE: Thread CFileCache start, auto delete: false 14:45:40 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:40 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:40 T:2702730304 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-53-arm.so) 14:45:40 T:2702730304 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avformat-53-arm.so 14:45:40 T:2702730304 DEBUG: Open - probing detected format [asf] 14:45:40 T:2702730304 DEBUG: Open - avformat_find_stream_info starting 14:45:40 T:2702730304 DEBUG: ffmpeg[A1186440]: [asf] parser not found for codec adpcm_ima_wav, packets or times may be invalid. 14:45:41 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:41 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:41 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:41 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:41 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:41 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:42 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:42 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:42 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:42 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:42 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:42 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:43 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:43 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:43 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:43 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:43 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:43 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:44 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:44 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:44 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:44 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:44 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:44 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:45 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:45 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:45 T:2702730304 DEBUG: ffmpeg[A1186440]: [asf] max_analyze_duration 5000000 reached at 5084000 14:45:45 T:2702730304 DEBUG: ffmpeg[A1186440]: [asf] Estimating duration from bitrate, this may be inaccurate 14:45:45 T:2702730304 DEBUG: Open - av_find_stream_info finished 14:45:45 T:2702730304 INFO: ffmpeg[A1186440]: Input #0, asf, from 'http://dyndns.dyndns.com:5555/videostream.asf?user=xx&pwd=xxx&resolution=8': 14:45:45 T:2702730304 INFO: ffmpeg[A1186440]: Duration: N/A, start: 0.000000, bitrate: 32 kb/s 14:45:45 T:2702730304 INFO: ffmpeg[A1186440]: Stream #0:0: Video: mjpeg (MJPG / 0x47504A4D), yuvj422p, 320x240, 100 tbr, 1k tbn, 1k tbc 14:45:45 T:2702730304 INFO: ffmpeg[A1186440]: Stream #0:1: Audio: adpcm_ima_wav ([17][0][0][0] / 0x0011), 8000 Hz, 1 channels, s16, 32 kb/s 14:45:45 T:2702730304 NOTICE: Opening video stream: 0 source: 256 14:45:45 T:2702730304 DEBUG: DllBcm: Using omx system library 14:45:45 T:2702730304 DEBUG: OMXClock::OMXStop 14:45:45 T:2702730304 ERROR: Boblight is disabled 14:45:45 T:2702730304 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_decode handle 0x219a1e0 dllopen : 1 14:45:45 T:2702730304 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131 14:45:45 T:2998596672 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1400 14:45:45 T:2998596672 DEBUG: CecLogMessage - physical address changed to 1400 14:45:45 T:2998596672 DEBUG: CecLogMessage - physical address unchanged (1400) 14:45:45 T:2702730304 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_render handle 0x28a5cf8 dllopen : 1 14:45:45 T:2702730304 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90 14:45:45 T:2702730304 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.video_scheduler handle 0x219c510 dllopen : 1 14:45:45 T:2702730304 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11 14:45:45 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:45 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:45 T:2702730304 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(60), nBufferSize(81920), nBufferAlignmen(16) 14:45:45 T:2702730304 DEBUG: COMXVideo::Open - decoder_component(0x0x219a1e0), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 0 14:45:45 T:2702730304 INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-mjpeg width 320 height 240 profile -99 fps 100.000000 14:45:45 T:2702730304 INFO: OMXPlayerVideo::OpenDecoder fps: 100.000000 hdmi_ntsc_freqs 0 14:45:45 T:2702730304 NOTICE: OMXClock using video as reference 14:45:45 T:2702730304 DEBUG: OMXClock::OMXReset audio / video : 0 / 1 start audio / video : 0 / 1 wait mask 2 14:45:45 T:2702730304 NOTICE: Creating video thread 14:45:45 T:2702730304 DEBUG: OMXClock::OMXSetSpeed 1 buffering 0 14:45:45 T:2848977984 NOTICE: Thread COMXPlayerVideo start, auto delete: false 14:45:45 T:2702730304 NOTICE: Opening audio stream: 1 source: 256 14:45:45 T:2702730304 DEBUG: DllBcm: Using omx system library 14:45:45 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0 14:45:45 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 14:45:45 T:2702730304 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swresample-0-arm.so) 14:45:45 T:2702730304 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/swresample-0-arm.so 14:45:45 T:2702730304 NOTICE: Creating audio thread 14:45:45 T:2702730304 DEBUG: OMXClock::OMXSetSpeed 1 buffering 0 14:45:45 T:2857366592 NOTICE: Thread COMXPlayerAudio start, auto delete: false 14:45:45 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0 14:45:45 T:2702730304 DEBUG: COMXPlayer::SetCaching - caching state 3 14:45:45 T:2702730304 DEBUG: OMXClock::OMXSetSpeed 0 buffering 0 14:45:45 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0 14:45:45 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0 14:45:45 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0 14:45:45 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0 14:45:45 T:2848977984 INFO: COMXPlayerVideo - Stillframe left, switching to normal playback 14:45:45 T:2848977984 DEBUG: OMXVideo::Decode VDec : setStartTime 0.000000 14:45:45 T:2848977984 ERROR: Output - renderer not started 14:45:45 T:2702730304 DEBUG: COMXPlayer::HandleMessages - player started 2 14:45:45 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:45 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:45 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::GENERAL_RESYNC(0.000000, 0) 14:45:45 T:2857366592 INFO: COMXAudioCodecOMX::GetChannelMap - FFmpeg reported 1 channels, but the layout contains 0 ignoring 14:45:45 T:2857366592 DEBUG: OMXClock::OMXStop 14:45:45 T:2857366592 DEBUG: COMXAudio::SetCodingType OMX_AUDIO_CodingPCM 14:45:45 T:2857366592 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_render handle 0x24cfef8 dllopen : 1 14:45:45 T:2857366592 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 14:45:45 T:2857366592 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_decode handle 0x261d2f0 dllopen : 1 14:45:45 T:2857366592 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_decode input port 120 output port 121 14:45:45 T:2857366592 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.audio_mixer handle 0x26015b0 dllopen : 1 14:45:45 T:2857366592 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 14:45:45 T:2857366592 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_decode) - port(120), nBufferCountMin(4), nBufferCountActual(5), nBufferSize(6144), nBufferAlignmen(16) 14:45:45 T:3041431552 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0xa842bd50 dllopen : 1 14:45:45 T:3041431552 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 14:45:45 T:3041431552 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0xa83ab228 dllopen : 1 14:45:45 T:3041431552 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 14:45:45 T:3041431552 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16) 14:45:46 T:2857366592 DEBUG: COMXAudio::Initialize Ouput bps 16 samplerate 8000 channels 2 buffer size 32000 bytes per second 16000 14:45:46 T:2857366592 DEBUG: COMXAudio::Initialize Input bps 16 samplerate 8000 channels 1 buffer size 32000 bytes per second 16000 14:45:46 T:2857366592 DEBUG: COMXAudio::Initialize device hdmi passthrough 0 hwdecode 0 14:45:46 T:2857366592 INFO: Audio codec channels 1 samplerate 8000 bitspersample 4 14:45:46 T:2857366592 NOTICE: OMXClock using audio as reference 14:45:46 T:2857366592 DEBUG: OMXClock::OMXReset audio / video : 1 / 1 start audio / video : 1 / 1 wait mask 3 14:45:46 T:2857366592 DEBUG: COMXAudio::Decode ADec : setStartTime 0.040000 14:45:46 T:2857366592 DEBUG: pcm->direction : input 14:45:46 T:2857366592 DEBUG: pcm->nPortIndex : 232 14:45:46 T:2857366592 DEBUG: pcm->eNumData : 0 14:45:46 T:2857366592 DEBUG: pcm->eEndian : 1 14:45:46 T:2857366592 DEBUG: pcm->bInterleaved : 1 14:45:46 T:2857366592 DEBUG: pcm->nBitPerSample : 16 14:45:46 T:2857366592 DEBUG: pcm->ePCMMode : 0 14:45:46 T:2857366592 DEBUG: pcm->nChannels : 1 14:45:46 T:2857366592 DEBUG: pcm->nSamplingRate : 8000 14:45:46 T:2857366592 DEBUG: OMX_AUDIO_ChannelCF 14:45:46 T:2857366592 DEBUG: OMX_AUDIO_ChannelRF 14:45:46 T:2857366592 DEBUG: pcm->direction : output 14:45:46 T:2857366592 DEBUG: pcm->nPortIndex : 100 14:45:46 T:2857366592 DEBUG: pcm->eNumData : 0 14:45:46 T:2857366592 DEBUG: pcm->eEndian : 1 14:45:46 T:2857366592 DEBUG: pcm->bInterleaved : 1 14:45:46 T:2857366592 DEBUG: pcm->nBitPerSample : 16 14:45:46 T:2857366592 DEBUG: pcm->ePCMMode : 0 14:45:46 T:2857366592 DEBUG: pcm->nChannels : 2 14:45:46 T:2857366592 DEBUG: pcm->nSamplingRate : 8000 14:45:46 T:2857366592 DEBUG: OMX_AUDIO_ChannelLF 14:45:46 T:2857366592 DEBUG: OMX_AUDIO_ChannelRF 14:45:46 T:2702730304 DEBUG: COMXPlayer::HandleMessages - player started 1 14:45:46 T:2702730304 DEBUG: COMXPlayer::SetCaching - caching state 4 14:45:46 T:2702730304 DEBUG: OMXClock::OMXSetSpeed 1 buffering 0 14:45:46 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 1000 14:45:46 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000 14:45:46 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 1) 14:45:46 T:2848977984 DEBUG: OMXVideo::Decode VDec : setStartTime 0.040000 14:45:46 T:2848977984 ERROR: Output - renderer not started 14:45:46 T:2857366592 ERROR: Previous line repeats 6 times. 14:45:46 T:2857366592 DEBUG: OMXClock::OMXAudioBufferStart 14:45:46 T:2848977984 ERROR: Output - renderer not started 14:45:46 T:2882532416 ERROR: Previous line repeats 19 times. 14:45:46 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:46 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:46 T:3041431552 ERROR: COMXCoreComponent::WaitForEvent OMX.broadcom.image_decode wait event 0x00000003 timeout 1000 14:45:46 T:3041431552 ERROR: COMXImage::Decode HandlePortSettingChange() failed 14:45:47 T:2848977984 ERROR: Output - renderer not started 14:45:47 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:47 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:47 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:47 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:47 T:2848977984 ERROR: Output - renderer not started 14:45:48 T:2857366592 ERROR: Previous line repeats 3 times. 14:45:48 T:2857366592 DEBUG: OMXClock::OMXAudioBufferStop 14:45:48 T:2857366592 DEBUG: OMXClock::OMXSetSpeed 1 buffering 0 14:45:48 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:48 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:48 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:48 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:48 T:2702730304 DEBUG: COMXPlayer::SetCaching - caching state 0 14:45:48 T:2848977984 ERROR: Output - renderer not started 14:45:48 T:2857366592 DEBUG: OMXClock::OMXAudioBufferStart 14:45:48 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:48 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:48 T:2702730304 DEBUG: COMXPlayer::SetCaching - caching state 1 14:45:48 T:2702730304 DEBUG: OMXClock::OMXSetSpeed 0 buffering 0 14:45:48 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0 14:45:48 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 1 14:45:48 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0 14:45:48 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 1 14:45:48 T:2702730304 DEBUG: COMXPlayer::HandleMessages - player started 1 14:45:48 T:2702730304 DEBUG: COMXPlayer::HandleMessages - player started 2 14:45:48 T:3041431552 ERROR: COMXCoreComponent::WaitForCommand OMX.broadcom.image_decode wait timeout event.eEvent 0x00000000 event.command 0x00000002 event.nData2 320 14:45:49 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:49 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:49 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:49 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:49 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:49 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:50 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:50 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:50 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:50 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:50 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:50 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:50 T:3041431552 ERROR: COMXCoreComponent::WaitForCommand OMX.broadcom.image_decode wait timeout event.eEvent 0x00000000 event.command 0x00000001 event.nData2 320 14:45:51 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:51 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:51 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:51 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:51 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:51 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:52 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:52 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:52 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:52 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:52 T:3041431552 ERROR: COMXCoreComponent::WaitForCommand OMX.broadcom.image_decode wait timeout event.eEvent 0x00000000 event.command 0x00000001 event.nData2 321 14:45:52 T:3041431552 ERROR: COMXCoreComponent::SetStateForComponent - OMX.broadcom.image_decode failed with omx_err(0x80001000) 14:45:52 T:3041431552 ERROR: Previous line repeats 2 times. 14:45:52 T:3041431552 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0xa83ab228 dllopen : 1 14:45:53 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:53 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:54 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:54 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:54 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:54 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:54 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:54 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:54 T:3041431552 ERROR: COMXCoreComponent::WaitForCommand OMX.broadcom.image_decode wait timeout event.eEvent 0x00000000 event.command 0x00000001 event.nData2 320 14:45:55 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:55 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:55 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:55 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:55 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:55 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:56 T:2702730304 DEBUG: COMXPlayer::SetCaching - caching state 4 14:45:56 T:2702730304 DEBUG: OMXClock::OMXSetSpeed 1 buffering 0 14:45:56 T:2848977984 DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000 14:45:56 T:2857366592 DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 1000 14:45:56 T:2848977984 ERROR: Output - renderer not started 14:45:56 T:2857366592 DEBUG: OMXClock::OMXAudioBufferStart 14:45:56 T:2848977984 ERROR: Output - renderer not started 14:45:56 T:2857366592 ERROR: Previous line repeats 2 times. 14:45:56 T:2857366592 INFO: COMXPlayerAudio - Switching to normal playback 14:45:56 T:2857366592 DEBUG: OMXClock::OMXAudioBufferStop 14:45:56 T:2857366592 DEBUG: OMXClock::OMXSetSpeed 1 buffering 0 14:45:56 T:2848977984 ERROR: Output - renderer not started 14:45:56 T:2702730304 DEBUG: COMXPlayer::SetCaching - caching state 0 14:45:56 T:2848977984 ERROR: Output - renderer not started 14:45:56 T:2882532416 ERROR: Previous line repeats 27 times. 14:45:56 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:56 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:56 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:56 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:56 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:56 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:56 T:2848977984 ERROR: Output - renderer not started 14:45:56 T:3041431552 ERROR: Previous line repeats 1 times. 14:45:56 T:3041431552 ERROR: COMXCoreComponent::WaitForCommand OMX.broadcom.image_decode wait timeout event.eEvent 0x00000000 event.command 0x00000001 event.nData2 321 14:45:56 T:3041431552 ERROR: COMXCoreComponent::SetStateForComponent - OMX.broadcom.image_decode failed with omx_err(0x80001000) 14:45:56 T:3041431552 ERROR: Previous line repeats 2 times. 14:45:56 T:3041431552 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0xa842bd50 dllopen : 1 14:45:57 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:57 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:57 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:57 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:57 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:57 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:58 T:2744828992 DEBUG: Thread Jobworker 2744828992 terminating (autodelete) 14:45:58 T:2811937856 DEBUG: Thread Jobworker 2811937856 terminating (autodelete) 14:45:58 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:58 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:58 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:58 T:2882532416 DEBUG: CecLogMessage - << 10 14:45:58 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:45:58 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:45:59 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:45:59 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:00 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:00 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:00 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:00 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:46:01 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:46:01 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:01 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:01 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:01 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:01 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:46:02 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:46:02 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:02 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:02 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:02 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:02 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:46:03 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:46:03 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:03 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:03 T:2882532416 DEBUG: CecLogMessage - << 10 14:46:03 T:2882532416 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller 14:46:03 T:2882532416 DEBUG: CecLogMessage - >> POLL not sent 14:46:04 T:2882532416 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL 14:46:04 T:2882532416 DEBUG: CecLogMessage - << 10