13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 1, type H264, codec: 28 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 2, type MPEG2AUDIO, codec: 86016 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 3, type MPEG2AUDIO, codec: 86016 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 4, type TELETEXT, codec: 94215 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 5, type DVBSUB, codec: 94209 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 6, type AC3, codec: 86019 13:02:46.800 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux stream change 13:02:46.800 T:3609269152 DEBUG: CDVDDemuxClient::RequestStreams(): added/updated stream 1 with codec_id 28 13:02:46.800 T:3609269152 DEBUG: CDVDDemuxClient::RequestStreams(): added/updated stream 2 with codec_id 86016 13:02:46.800 T:3609269152 DEBUG: CDVDDemuxClient::RequestStreams(): added/updated stream 3 with codec_id 86016 13:02:46.800 T:3609269152 DEBUG: CDVDDemuxClient::RequestStreams(): added/updated stream 4 with codec_id 94215 13:02:46.801 T:3609269152 DEBUG: CDVDDemuxClient::RequestStreams(): added/updated stream 5 with codec_id 94209 13:02:46.801 T:3609269152 DEBUG: CDVDDemuxClient::RequestStreams(): added/updated stream 6 with codec_id 86019 13:02:46.801 T:3609269152 NOTICE: Opening stream: 1 source: 256 13:02:46.801 T:3609269152 NOTICE: Creating video codec with codec id: 28 13:02:46.801 T:3609269152 DEBUG: FactoryCodec - Video: amcodec - Opening 13:02:46.801 T:3609269152 DEBUG: SECTION:LoadDLL(libamplayer.so) 13:02:46.802 T:3609269152 DEBUG: Loading: libamplayer.so 13:02:46.803 T:3609269152 ERROR: Unable to load libamplayer.so, reason: libamplayer.so: cannot open shared object file: No such file or directory 13:02:46.803 T:3609269152 DEBUG: Dll libamplayer.so was not found in path 13:02:46.803 T:3609269152 WARNING: CAMLCodec::CAMLCodec libamplayer.so not found, trying libamcodec.so instead 13:02:46.803 T:3609269152 DEBUG: SECTION:LoadDLL(libamcodec.so) 13:02:46.804 T:3609269152 DEBUG: Loading: libamcodec.so 13:02:46.806 T:3609269152 INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec 13:02:46.806 T:3609269152 DEBUG: FactoryCodec - Video: am-h264 - Opened 13:02:46.806 T:3609269152 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 13:02:46.806 T:3609269152 NOTICE: Creating video thread 13:02:46.806 T:3663385504 DEBUG: Thread VideoPlayerVideo start, auto delete: false 13:02:46.806 T:3663385504 NOTICE: running thread: video_thread 13:02:46.806 T:3609269152 NOTICE: Opening stream: 6 source: 256 13:02:46.806 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(0) 13:02:46.806 T:3609269152 NOTICE: Finding audio codec for: 86019 13:02:46.806 T:3609269152 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 13:02:46.807 T:3609269152 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 13:02:46.807 T:3609269152 NOTICE: Creating audio thread 13:02:46.807 T:3609269152 NOTICE: Opening stream: 5 source: 256 13:02:46.807 T:3599758240 DEBUG: Thread VideoPlayerAudio start, auto delete: false 13:02:46.807 T:3609269152 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 13:02:46.807 T:3599758240 NOTICE: running thread: CVideoPlayerAudio::Process() 13:02:46.807 T:3599758240 DEBUG: CDVDAudio::Pause - pausing audio stream 13:02:46.807 T:3609269152 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened 13:02:46.807 T:3609269152 NOTICE: Opening stream: 4 source: 256 13:02:46.807 T:3609269152 NOTICE: waiting for teletext data thread to exit 13:02:46.809 T:3609269152 NOTICE: Creating teletext data thread 13:02:46.809 T:3617657760 DEBUG: Thread DVDTeletextData start, auto delete: false 13:02:46.809 T:3617657760 NOTICE: running thread: CDVDTeletextData 13:02:46.809 T:3609269152 DEBUG: CDVDDemuxClient::ParsePacket - {2} samplerate changed from 3 to 48000 13:02:46.809 T:3609269152 DEBUG: CDVDDemuxClient::ParsePacket - {3} samplerate changed from 3 to 48000 13:02:46.810 T:3609269152 DEBUG: CDVDDemuxClient::ParsePacket - {6} samplerate changed from 3 to 48000 13:02:46.810 T:3609269152 NOTICE: Opening stream: 1 source: 256 13:02:46.810 T:3609269152 NOTICE: Opening stream: 6 source: 256 13:02:46.810 T:3609269152 NOTICE: Finding audio codec for: 86019 13:02:46.810 T:3609269152 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 13:02:46.811 T:3609269152 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 13:02:46.811 T:3609269152 NOTICE: Closing stream player 3 13:02:46.811 T:3609269152 NOTICE: Opening stream: 5 source: 256 13:02:46.811 T:3609269152 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 13:02:46.811 T:3609269152 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened 13:02:46.811 T:3609269152 NOTICE: Opening stream: 4 source: 256 13:02:46.814 T:3609269152 DEBUG: CDVDDemuxClient::ParsePacket - {1} profile changed from -99 to 100 13:02:46.814 T:3609269152 DEBUG: CDVDDemuxClient::ParsePacket - {1} level changed from -99 to 40 13:02:46.815 T:3609269152 NOTICE: Opening stream: 1 source: 256 13:02:46.815 T:3609269152 NOTICE: Creating video codec with codec id: 28 13:02:46.815 T:3609269152 DEBUG: FactoryCodec - Video: amcodec - Opening 13:02:46.815 T:3609269152 DEBUG: SECTION:LoadDLL(libamplayer.so) 13:02:46.816 T:3609269152 DEBUG: Loading: libamplayer.so 13:02:46.816 T:3609269152 ERROR: Unable to load libamplayer.so, reason: libamplayer.so: cannot open shared object file: No such file or directory 13:02:46.816 T:3609269152 DEBUG: Dll libamplayer.so was not found in path 13:02:46.816 T:3609269152 WARNING: CAMLCodec::CAMLCodec libamplayer.so not found, trying libamcodec.so instead 13:02:46.816 T:3609269152 INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec 13:02:46.816 T:3609269152 DEBUG: FactoryCodec - Video: am-h264 - Opened 13:02:46.816 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(0) 13:02:46.817 T:3609269152 NOTICE: Opening stream: 6 source: 256 13:02:46.817 T:3609269152 NOTICE: Closing stream player 3 13:02:46.817 T:3609269152 NOTICE: Opening stream: 5 source: 256 13:02:46.817 T:3609269152 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 13:02:46.817 T:3663385504 DEBUG: codecid_to_vformat, id(28) -> vformat(2) 13:02:46.817 T:3663385504 DEBUG: codec_tag_to_vdec_type, codec_tag(28) -> vdec_type(4) 13:02:46.817 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.width(1280), hints.height(720), hints.codec(28), hints.codec_tag(0) 13:02:46.817 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(1000000), hints.fpsscale(20000), video_rate(1920) 13:02:46.817 T:3609269152 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened 13:02:46.817 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.aspect(0.000000), video_ratio.num(1), video_ratio.den(1) 13:02:46.817 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(0) 13:02:46.817 T:3609269152 NOTICE: Opening stream: 4 source: 256 13:02:46.817 T:3599758240 DEBUG: CDVDAudio::Pause - pausing audio stream 13:02:46.818 T:3599758240 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through) 13:02:46.820 T:3599758240 DEBUG: CVideoPlayerAudio:: synctype set to 1: resample 13:02:46.849 T:3925865376 DEBUG: Thread CAMLCodec start, auto delete: false 13:02:46.849 T:3925865376 DEBUG: CAMLCodec::Process Started 13:02:46.849 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(1000) 13:02:46.863 T:4085248928 INFO: CActiveAESink::OpenSink - initialize sink 13:02:46.874 T:3663385504 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 13:02:46.874 T:3663385504 DEBUG: CAMLCodec::CloseDecoder 13:02:46.880 T:3925865376 DEBUG: CAMLCodec::Process Stopped 13:02:46.880 T:3925865376 DEBUG: Thread CAMLCodec 3925865376 terminating 13:02:46.896 T:3663385504 DEBUG: codecid_to_vformat, id(28) -> vformat(2) 13:02:46.896 T:3663385504 DEBUG: codec_tag_to_vdec_type, codec_tag(28) -> vdec_type(4) 13:02:46.896 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.width(1280), hints.height(720), hints.codec(28), hints.codec_tag(0) 13:02:46.896 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(1000000), hints.fpsscale(20000), video_rate(1920) 13:02:46.896 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.aspect(0.000000), video_ratio.num(1), video_ratio.den(1) 13:02:46.897 T:3663385504 DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(147) 13:02:46.913 T:3925865376 DEBUG: Thread CAMLCodec start, auto delete: false 13:02:46.913 T:3925865376 DEBUG: CAMLCodec::Process Started 13:02:46.913 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(1000) 13:02:47.049 T:4085248928 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=AMLM8AUDIO,DEV=0 13:02:47.049 T:4085248928 INFO: CAESinkALSA::Initialize - Attempting to open device "default" 13:02:47.052 T:4085248928 INFO: CAESinkALSA::Initialize - Opened device "default" 13:02:47.053 T:4085248928 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 13:02:47.053 T:4085248928 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 13:02:47.053 T:4085248928 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 13:02:47.053 T:4085248928 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 128, bufferSize 9600 13:02:47.054 T:4085248928 DEBUG: Audio Driver reports too low periodSize 128 - will use 2 fragments 13:02:47.054 T:4085248928 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 13:02:47.054 T:4085248928 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 13:02:47.054 T:4085248928 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 13:02:47.054 T:4085248928 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 13:02:47.054 T:4085248928 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 13:02:47.054 T:4085248928 DEBUG: Output Device : AML-M8AUDIO 13:02:47.054 T:4085248928 DEBUG: Sample Rate : 48000 13:02:47.054 T:4085248928 DEBUG: Sample Format : AE_FMT_S32NE 13:02:47.054 T:4085248928 DEBUG: Channel Count : 2 13:02:47.054 T:4085248928 DEBUG: Channel Layout: FL,FR 13:02:47.054 T:4085248928 DEBUG: Frames : 256 13:02:47.054 T:4085248928 DEBUG: Frame Size : 8 13:02:47.057 T:4095550368 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 13:02:47.080 T:3609269152 DEBUG: Previous line repeats 1 times. 13:02:47.080 T:3609269152 DEBUG: CVideoPlayer::HandleMessages - player started 1 13:02:47.542 T:3663385504 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 50.000000 fps 13:02:47.587 T:3663385504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 13:02:47.612 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:47.623 T:3663385504 DEBUG: CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 50.00. format: AMLCODEC 13:02:47.624 T:4117148080 DEBUG: DeleteRenderer - deleting renderer 13:02:47.624 T:4117148080 DEBUG: LinuxRendererGL: Cleaning up GL resources 13:02:47.624 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.624 T:4117148080 NOTICE: Using GL_TEXTURE_2D 13:02:47.679 T:4117148080 NOTICE: GL: Using AML render method 13:02:47.679 T:4117148080 DEBUG: GL: Requested render method: 0 13:02:47.679 T:4117148080 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 13:02:47.679 T:4117148080 ERROR: GL: BaseYUV2RGBGLSLShader - unsupported format 19 13:02:47.679 T:4117148080 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: 13:02:47.679 T:4117148080 ERROR: GL: BaseYUV2RGBGLSLShader - unsupported format 19 13:02:47.679 T:4117148080 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: 13:02:47.685 T:4117148080 DEBUG: GL: Vertex Shader compilation log: 13:02:47.685 T:4117148080 DEBUG: GL: Vertex Shader compiled successfully 13:02:47.686 T:4117148080 DEBUG: GL: Pixel Shader compilation log: 13:02:47.686 T:4117148080 DEBUG: GL: Fragment Shader compiled successfully 13:02:47.692 T:4117148080 DEBUG: GL: Vertex Shader compilation log: 13:02:47.692 T:4117148080 DEBUG: GL: Vertex Shader compiled successfully 13:02:47.696 T:4117148080 DEBUG: GL: Pixel Shader compilation log: 13:02:47.696 T:4117148080 DEBUG: GL: Fragment Shader compiled successfully 13:02:47.696 T:4117148080 WARNING: CLinuxRendererGLES::UpdateVideoFilter - choosen scaling method 1, is not supported by renderer 13:02:47.696 T:4117148080 NOTICE: GL: NPOT texture support detected 13:02:47.696 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 720 13:02:47.697 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 360 13:02:47.697 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.697 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.697 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.697 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.697 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.697 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.697 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.697 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 720 13:02:47.697 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 360 13:02:47.698 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.698 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.698 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 720 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 360 13:02:47.698 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.698 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.698 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.699 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 720 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 360 13:02:47.699 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.699 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 1280 x 360 13:02:47.699 T:4117148080 DEBUG: GL: Creating YUV NPOT texture of size 640 x 180 13:02:47.700 T:4117148080 DEBUG: Previous line repeats 1 times. 13:02:47.700 T:4117148080 DEBUG: CRenderManager::Configure - 4 13:02:47.703 T:4117148080 DEBUG: CAMLCodec::SetVideo3dMode:mode3d(0x0) 13:02:47.703 T:4117148080 DEBUG: CAMLCodec::SetVideoRect:display(0,0,1920,1080) 13:02:47.703 T:4117148080 DEBUG: CAMLCodec::SetVideoRect:gui(0,0,1920,1080) 13:02:47.703 T:4117148080 DEBUG: CAMLCodec::SetVideoRect:m_dst_rect(0,0,1920,1080) 13:02:47.703 T:4117148080 DEBUG: CAMLCodec::SetVideoRect:dst_rect(0,0,1920,1080) 13:02:47.703 T:4117148080 DEBUG: CAMLCodec::SetVideoRect:m_stereo_mode(0) 13:02:47.703 T:4117148080 DEBUG: CAMLCodec::SetVideoRect:m_stereo_view(0) 13:02:47.713 T:4117148080 DEBUG: Activating window ID: 12005 13:02:47.713 T:4117148080 DEBUG: ------ Window Deinit (Home.xml) ------ 13:02:47.715 T:4117148080 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 13:02:48.054 T:3609269152 DEBUG: CVideoPlayer::HandleMessages - player started 2 13:02:48.055 T:3609269152 DEBUG: CVideoPlayer::SetCaching - caching state 3 13:02:48.056 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:48.056 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(1000) 13:02:48.057 T:3609269152 DEBUG: CVideoPlayer::SetCaching - caching state 0 13:02:48.057 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:48.057 T:3609269152 DEBUG: VideoPlayer::Sync - Audio - pts: 289822.000000, cache: 321881.971159, totalcache: 600000.023842 13:02:48.057 T:3609269152 DEBUG: VideoPlayer::Sync - Video - pts: 1718033.333333, cache: 50000.000000, totalcache: 100000.000000 13:02:48.057 T:3599758240 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-710178.023842) 13:02:48.057 T:3599758240 DEBUG: CDVDAudio::Resume - resume audio stream 13:02:48.057 T:3663385504 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-710178.023842) 13:02:48.057 T:4095550368 DEBUG: ActiveAE - start sync of audio stream 13:02:48.058 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 13:02:48.061 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:48.079 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 13:02:48.159 T:4095550368 DEBUG: ActiveAE::SyncStream - average error of 564.988563, start adjusting 13:02:48.647 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840) 13:02:48.672 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:48.783 T:4095550368 DEBUG: ActiveAE::SyncStream - average error 26.356244 below threshold of 30.000000 13:02:50.104 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840) 13:02:50.254 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:50.455 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840) 13:02:50.506 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:51.065 T:3599758240 NOTICE: CVideoPlayerAudio::Process - stream stalled 13:02:51.065 T:3609269152 DEBUG: Stream stalled, start buffering. Audio: 0 - Video: 22 13:02:51.065 T:3609269152 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 13:02:51.067 T:3599758240 DEBUG: CDVDAudio::Flush - flush audio stream 13:02:51.067 T:3599758240 DEBUG: CDVDAudio::Pause - pausing audio stream 13:02:51.271 T:3663385504 DEBUG: CAMLCodec::Reset 13:02:51.296 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(1000) 13:02:51.297 T:3599758240 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 13:02:51.298 T:3609269152 DEBUG: CVideoPlayer::SetCaching - caching state 2 13:02:51.298 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:51.300 T:3663385504 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 13:02:51.300 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(0) 13:02:51.300 T:3663385504 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 13:02:51.308 T:3599758240 DEBUG: CDVDAudio::Pause - pausing audio stream 13:02:51.381 T:3609269152 DEBUG: CVideoPlayer::HandleMessages - player started 1 13:02:51.837 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840) 13:02:51.863 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:52.207 T:3609269152 DEBUG: CVideoPlayer::HandleMessages - player started 2 13:02:52.207 T:3609269152 DEBUG: CVideoPlayer::SetCaching - caching state 3 13:02:52.207 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:52.207 T:3609269152 DEBUG: CVideoPlayer::SetCaching - caching state 0 13:02:52.207 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:52.207 T:3609269152 DEBUG: VideoPlayer::Sync - Audio - pts: 4769822.000000, cache: 309512.601652, totalcache: 600000.023842 13:02:52.207 T:3609269152 DEBUG: VideoPlayer::Sync - Video - pts: 6198033.333333, cache: 50000.000000, totalcache: 100000.000000 13:02:52.207 T:3599758240 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(3769821.976158) 13:02:52.207 T:3599758240 DEBUG: CDVDAudio::Resume - resume audio stream 13:02:52.208 T:4095550368 DEBUG: ActiveAE - start sync of audio stream 13:02:52.210 T:3663385504 DEBUG: CAMLCodec::SetSpeed, speed(1000) 13:02:52.210 T:3663385504 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(3769821.976158) 13:02:52.222 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 13:02:52.308 T:4095550368 DEBUG: ActiveAE::SyncStream - average error of 668.262262, start adjusting 13:02:52.477 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840) 13:02:52.502 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:53.029 T:4095550368 DEBUG: ActiveAE::SyncStream - average error 27.142020 below threshold of 30.000000 13:02:53.122 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:53.238 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840) 13:02:53.251 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 13:02:53.338 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:53.589 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840) 13:02:53.640 T:3663385504 DEBUG: DVDVideoCodecAmlogic: detected new framerate(50.000000), video_rate(1920) 13:02:55.039 T:4095550368 DEBUG: ActiveAE::SyncStream - average error 1642.214643 above threshold of 1000.000000 13:02:55.734 T:3599758240 NOTICE: CVideoPlayerAudio::Process - stream stalled 13:02:55.742 T:3609269152 DEBUG: Stream stalled, start buffering. Audio: 0 - Video: 20 13:02:55.742 T:3609269152 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 13:02:55.744 T:3599758240 DEBUG: CDVDAudio::Flush - flush audio stream 13:02:55.744 T:3599758240 DEBUG: CDVDAudio::Pause - pausing audio stream 13:02:56.742 T:3609269152 DEBUG: CDVDMsgGeneralSynchronize - global timeout 13:02:56.742 T:3609269152 DEBUG: CVideoPlayer::SetCaching - caching state 2 13:02:56.742 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:02:56.744 T:3599758240 DEBUG: CDVDMsgGeneralSynchronize - global timeout 13:02:56.744 T:3599758240 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 13:02:56.744 T:3599758240 DEBUG: CDVDAudio::Pause - pausing audio stream 13:02:56.772 T:3609269152 DEBUG: CVideoPlayer::HandleMessages - player started 1 13:03:03.834 T:3609269152 DEBUG: CVideoPlayer::SetCaching - caching state 0 13:03:03.834 T:3609269152 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 13:03:11.329 T:3626046368 DEBUG: Thread JobWorker 3626046368 terminating (autodelete) 13:03:18.223 T:3969696672 DEBUG: PushCecKeypress - received key e0 duration 1000 13:03:18.233 T:4117148080 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 13:03:18.233 T:4117148080 NOTICE: CVideoPlayer::CloseFile() 13:03:18.234 T:4117148080 NOTICE: VideoPlayer: waiting for threads to exit 13:03:18.236 T:3609269152 NOTICE: CVideoPlayer::OnExit() 13:03:18.236 T:3609269152 NOTICE: Closing stream player 1 13:03:18.236 T:3609269152 NOTICE: Waiting for audio thread to exit 13:03:18.236 T:3599758240 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 13:03:18.236 T:3599758240 NOTICE: thread end: CVideoPlayerAudio::OnExit() 13:03:18.236 T:3599758240 DEBUG: Thread VideoPlayerAudio 3599758240 terminating 13:03:18.236 T:3609269152 NOTICE: Closing audio device 13:03:18.239 T:3609269152 DEBUG: CDVDAudio::Flush - flush audio stream 13:03:18.239 T:4095550368 DEBUG: CActiveAE::DiscardStream - audio stream deleted 13:03:18.239 T:4095550368 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 13:03:18.240 T:3609269152 DEBUG: Previous line repeats 2 times. 13:03:18.240 T:3609269152 NOTICE: Deleting audio codec 13:03:18.240 T:3609269152 NOTICE: Closing stream player 2 13:03:18.240 T:3609269152 NOTICE: waiting for video thread to exit 13:03:18.244 T:4085248928 INFO: CActiveAESink::OpenSink - initialize sink 13:03:18.249 T:3663385504 NOTICE: thread end: video_thread 13:03:18.249 T:3663385504 DEBUG: Thread VideoPlayerVideo 3663385504 terminating 13:03:18.249 T:3609269152 NOTICE: deleting video codec 13:03:18.249 T:3609269152 DEBUG: CAMLCodec::CloseDecoder 13:03:18.254 T:3925865376 DEBUG: CAMLCodec::Process Stopped 13:03:18.254 T:3925865376 DEBUG: Thread CAMLCodec 3925865376 terminating 13:03:18.259 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1 13:03:18.259 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 4 13:03:18.265 T:3609269152 NOTICE: Closing stream player 3 13:03:18.265 T:3609269152 NOTICE: Closing stream player 4 13:03:18.265 T:3609269152 NOTICE: waiting for teletext data thread to exit 13:03:18.266 T:3617657760 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true (-1) 13:03:18.266 T:3617657760 NOTICE: thread end: data_thread 13:03:18.266 T:3617657760 DEBUG: Thread DVDTeletextData 3617657760 terminating 13:03:18.269 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2 13:03:18.269 T:3773756320 DEBUG: Previous line repeats 3 times. 13:03:18.269 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3 13:03:18.270 T:3773756320 DEBUG: Previous line repeats 3 times. 13:03:18.270 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1 13:03:18.316 T:3773756320 DEBUG: Previous line repeats 2 times. 13:03:18.316 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 4 13:03:18.316 T:3773756320 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1 13:03:18.318 T:3617657760 DEBUG: Thread JobWorker start, auto delete: true 13:03:18.318 T:3609269152 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux unsubscribe from 1755221722 13:03:18.318 T:3617657760 DEBUG: DoWork - Saving file state for video item pvr://channels/tv/Alle Kanäle/pvr.hts_1755221722.pvr 13:03:18.319 T:3609269152 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully unsubscribed from channel id 1755221722, subscription id 2 13:03:18.322 T:3609269152 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux close 13:03:18.323 T:3609269152 DEBUG: CDVDInputStreamPVRManager::Close - stream closed 13:03:18.323 T:3609269152 DEBUG: OnPlayBackStopped: play state was 2, starting 0 13:03:18.323 T:3609269152 DEBUG: Thread VideoPlayer 3609269152 terminating 13:03:18.323 T:4117148080 NOTICE: VideoPlayer: finished waiting 13:03:18.323 T:4117132192 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 13:03:18.323 T:4117132192 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 13:03:18.323 T:4117148080 DEBUG: DeleteRenderer - deleting renderer 13:03:18.323 T:4117148080 DEBUG: LinuxRendererGL: Cleaning up GL resources 13:03:18.324 T:4117148080 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 13:03:18.324 T:4117148080 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 13:03:18.333 T:4117148080 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 13:03:18.333 T:4117148080 DEBUG: ------ Window Init (Home.xml) ------ 13:03:18.334 T:4117148080 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing.. 13:03:18.335 T:4117148080 DEBUG: CDirectoryProvider[special://skin/playlists/mostplayed_albums.xsp]: refreshing.. 13:03:18.335 T:4117148080 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 13:03:18.335 T:4117148080 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 13:03:18.335 T:4117148080 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 13:03:18.335 T:3609269152 DEBUG: Thread JobWorker start, auto delete: true 13:03:18.335 T:4117148080 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 13:03:18.336 T:4117148080 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing.. 13:03:18.336 T:4117148080 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing.. 13:03:18.337 T:3925865376 DEBUG: Thread JobWorker start, auto delete: true 13:03:18.354 T:3609269152 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE (((CAST(albumview.iTimesPlayed as DECIMAL(5,1)) > 0))) AND (albumview.strReleaseType = 'album') 13:03:18.376 T:3617657760 DEBUG: RunQuery took 26 ms for 0 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00 13:03:18.383 T:3617657760 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/*?view=lastplayed) 13:03:18.385 T:3925865376 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/radio/*?view=lastplayed) 13:03:18.394 T:3609269152 DEBUG: GetAlbumsByWhere - query took 40 ms 13:03:18.444 T:4085248928 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=AMLM8AUDIO,DEV=0 13:03:18.445 T:4085248928 INFO: CAESinkALSA::Initialize - Attempting to open device "default" 13:03:18.448 T:4085248928 INFO: CAESinkALSA::Initialize - Opened device "default" 13:03:18.448 T:4085248928 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 13:03:18.449 T:4085248928 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 13:03:18.449 T:4085248928 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 13:03:18.449 T:4085248928 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 13:03:18.449 T:4085248928 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 13:03:18.449 T:4085248928 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 13:03:18.449 T:4085248928 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 13:03:18.449 T:4085248928 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 13:03:18.449 T:4085248928 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 13:03:18.450 T:4085248928 DEBUG: Output Device : AML-M8AUDIO 13:03:18.450 T:4085248928 DEBUG: Sample Rate : 44100 13:03:18.450 T:4085248928 DEBUG: Sample Format : AE_FMT_S32NE 13:03:18.450 T:4085248928 DEBUG: Channel Count : 2 13:03:18.450 T:4085248928 DEBUG: Channel Layout: FL,FR 13:03:18.450 T:4085248928 DEBUG: Frames : 2048 13:03:18.450 T:4085248928 DEBUG: Frame Size : 8 13:03:18.452 T:4095550368 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 13:03:18.495 T:4117148080 DEBUG: Previous line repeats 1 times. 13:03:18.495 T:4117148080 NOTICE: CVideoPlayer::CloseFile() 13:03:18.495 T:4117148080 NOTICE: VideoPlayer: waiting for threads to exit 13:03:18.495 T:4117148080 NOTICE: VideoPlayer: finished waiting 13:03:18.495 T:4117148080 NOTICE: CVideoPlayer::CloseFile() 13:03:18.495 T:4117148080 NOTICE: VideoPlayer: waiting for threads to exit 13:03:18.495 T:4117148080 NOTICE: VideoPlayer: finished waiting 13:03:18.497 T:4117148080 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData