2019-05-12 20:08:41.195 T:4090081856 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 2019-05-12 20:08:41.195 T:4090081856 NOTICE: Enabled debug logging due to GUI setting. Level 2. 2019-05-12 20:08:41.196 T:4090081856 DEBUG: ------ Window Init () ------ 2019-05-12 20:08:45.775 T:4090081856 NOTICE: Samba is idle. Closing the remaining connections 2019-05-12 20:08:54.090 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 2019-05-12 20:08:54.090 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 2019-05-12 20:08:54.090 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 2019-05-12 20:08:54.090 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 2019-05-12 20:08:54.113 T:4090081856 DEBUG: CPlayerCoreFactory::GetPlayers() 2019-05-12 20:08:54.113 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 2019-05-12 20:08:54.113 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 2019-05-12 20:08:54.113 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2019-05-12 20:08:54.113 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2019-05-12 20:08:54.113 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2019-05-12 20:08:54.113 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 2019-05-12 20:08:54.114 T:4090081856 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 2019-05-12 20:08:54.117 T:4090081856 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 2019-05-12 20:08:54.118 T:4090081856 NOTICE: VideoPlayer::OpenFile: 2019-05-12 20:08:54.118 T:4090081856 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted 2019-05-12 20:08:54.118 T:3607077760 DEBUG: Thread VideoPlayer start, auto delete: false 2019-05-12 20:08:54.119 T:3607077760 NOTICE: Creating InputStream 2019-05-12 20:08:54.119 T:3615470464 DEBUG: Thread JobWorker start, auto delete: true 2019-05-12 20:08:54.131 T:3615470464 DEBUG: Loading settings for 2019-05-12 20:08:54.156 T:4090081856 DEBUG: CVideoGUIInfo::InitCurrentItem() 2019-05-12 20:08:54.195 T:4090081856 DEBUG: CPlayerGUIInfo::InitCurrentItem() 2019-05-12 20:08:54.196 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc 2019-05-12 20:08:54.196 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged 2019-05-12 20:08:54.196 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 2019-05-12 20:08:54.196 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 2019-05-12 20:08:54.248 T:3607077760 DEBUG: CSMBFile::Open - opened , fd=10000 2019-05-12 20:08:54.256 T:3607077760 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 2019-05-12 20:08:54.271 T:3607077760 DEBUG: ScanForExternalSubtitles: END (total time: 15 ms) 2019-05-12 20:08:54.271 T:3607077760 NOTICE: Creating Demuxer 2019-05-12 20:08:54.296 T:4090081856 DEBUG: ------ Window Init (DialogBusy.xml) ------ 2019-05-12 20:08:54.352 T:3607077760 DEBUG: Open - probing detected format [matroska,webm] 2019-05-12 20:08:54.353 T:3607077760 DEBUG: Open - avformat_find_stream_info starting 2019-05-12 20:08:54.366 T:3607077760 DEBUG: Open - av_find_stream_info finished 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Input #0, matroska,webm, from '': 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: encoder : libebml v1.2.3 + libmatroska v1.3.0 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: creation_time : 2012-06-30T16:18:18.000000Z 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Duration: 00:54:09.22, start: 0.000000, bitrate: 4637 kb/s 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:0: start 0.000000, end 270.312000 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:00:00.000 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:1: start 270.312000, end 760.969000 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:04:30.312 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:2: start 760.969000, end 1377.543000 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:12:40.969 2019-05-12 20:08:54.367 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:3: start 1377.543000, end 1852.726000 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:22:57.543 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:4: start 1852.726000, end 2401.691000 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:30:52.726 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:5: start 2401.691000, end 2765.721000 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:40:01.691 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:6: start 2765.721000, end 3190.187000 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:46:05.721 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Chapter #0:7: start 3190.187000, end 3249.216000 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.368 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 00:53:10.187 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: Stream #0:0(eng): Video: h264 (High), yuv420p(progressive), 1280x720, SAR 1:1 DAR 16:9, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default) 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: title : 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: Stream #0:1(ger): Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s (default) 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: title : AC3 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: Stream #0:2(eng): Audio: ac3, 48000 Hz, 5.1(side), fltp, 640 kb/s 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: Metadata: 2019-05-12 20:08:54.369 T:3607077760 INFO: ffmpeg[D6FFA380]: title : AC3 2019-05-12 20:08:54.369 T:3607077760 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 2019-05-12 20:08:54.369 T:3607077760 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 2019-05-12 20:08:54.369 T:3607077760 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 2019-05-12 20:08:54.370 T:3607077760 NOTICE: Opening stream: 0 source: 256 2019-05-12 20:08:54.370 T:3607077760 NOTICE: Creating video codec with codec id: 27 2019-05-12 20:08:54.370 T:3607077760 NOTICE: CDVDVideoCodecDRMPRIME::Open - using decoder h264 (rkmpp) 2019-05-12 20:08:54.374 T:3607077760 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2019-05-12 20:08:54.374 T:3607077760 NOTICE: Creating video thread 2019-05-12 20:08:54.374 T:3607077760 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: 2019-05-12 20:08:54.375 T:3649041280 DEBUG: Thread VideoPlayerVideo start, auto delete: false 2019-05-12 20:08:54.375 T:3649041280 NOTICE: running thread: video_thread 2019-05-12 20:08:54.375 T:3649041280 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0 2019-05-12 20:08:54.375 T:3607077760 NOTICE: Opening stream: 1 source: 256 2019-05-12 20:08:54.376 T:3607077760 NOTICE: Finding audio codec for: 86019 2019-05-12 20:08:54.376 T:3607077760 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2019-05-12 20:08:54.376 T:3607077760 NOTICE: Creating audio thread 2019-05-12 20:08:54.377 T:3640648576 DEBUG: Thread VideoPlayerAudio start, auto delete: false 2019-05-12 20:08:54.377 T:3640648576 NOTICE: running thread: CVideoPlayerAudio::Process() 2019-05-12 20:08:54.377 T:3640648576 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 2019-05-12 20:08:54.377 T:3607077760 DEBUG: CVideoPlayer::SetCaching - caching state 2 2019-05-12 20:08:54.377 T:3607077760 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 2019-05-12 20:08:54.377 T:3607077760 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0 2019-05-12 20:08:54.377 T:3607077760 DEBUG: CVideoPlayer::HandleMessages - player 1 reported state: 0 2019-05-12 20:08:54.377 T:3615470464 DEBUG: OnAVChange: CApplication::OnAVChange 2019-05-12 20:08:54.378 T:3607077760 DEBUG: CVideoPlayer::SetCaching - caching state 1 2019-05-12 20:08:54.378 T:3649041280 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 2019-05-12 20:08:54.378 T:3607077760 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 2019-05-12 20:08:54.378 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc 2019-05-12 20:08:54.378 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange 2019-05-12 20:08:54.379 T:3607077760 DEBUG: CVideoPlayer::CheckContinuity - wrapback :2, prev:167000.000000, curr:0.000000, diff:-167000.000000 2019-05-12 20:08:54.387 T:3640648576 DEBUG: CDVDAudio::Pause - pausing audio stream 2019-05-12 20:08:54.388 T:3640648576 DEBUG: Previous line repeats 1 times. 2019-05-12 20:08:54.388 T:3640648576 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through) 2019-05-12 20:08:54.389 T:3640648576 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 2019-05-12 20:08:54.390 T:4060078976 INFO: CActiveAESink::OpenSink - initialize sink 2019-05-12 20:08:54.390 T:4060078976 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=I2S,DEV=0 2019-05-12 20:08:54.390 T:4060078976 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=I2S,DEV=0" 2019-05-12 20:08:54.391 T:3649041280 DEBUG: CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 23.98. 2019-05-12 20:08:54.399 T:4090081856 DEBUG: DeleteRenderer - deleting renderer 2019-05-12 20:08:54.399 T:4090081856 DEBUG: LinuxRendererGLES: Cleaning up GLES resources 2019-05-12 20:08:54.399 T:4090081856 DEBUG: CRenderManager::Configure - 5 2019-05-12 20:08:54.402 T:3607077760 DEBUG: CVideoPlayer::HandleMessages - player started 2 2019-05-12 20:08:54.402 T:3615470464 DEBUG: OnAVChange: CApplication::OnAVChange 2019-05-12 20:08:54.402 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc 2019-05-12 20:08:54.402 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange 2019-05-12 20:08:54.404 T:4090081856 NOTICE: CVideoLayerBridgeRockchip::Configure - format=842094158 is10bit=false width=1280 height=720 colorspace=2 color_primaries=2 color_trc=2 color_range=1 eotf=0 blob_id=0 2019-05-12 20:08:54.423 T:4090081856 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 76 2019-05-12 20:08:54.423 T:4090081856 DEBUG: CDRMUtils::DrmFbGetFromBo - using modifier: 0 2019-05-12 20:08:54.462 T:4090081856 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 109 2019-05-12 20:08:54.462 T:4090081856 DEBUG: CDRMUtils::DrmFbGetFromBo - using modifier: 0 2019-05-12 20:08:54.615 T:4060078976 INFO: CAESinkALSA::Initialize - Opened device "sysdefault:CARD=I2S" 2019-05-12 20:08:54.616 T:4060078976 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2019-05-12 20:08:54.616 T:4060078976 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4 2019-05-12 20:08:54.616 T:4060078976 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 2019-05-12 20:08:54.617 T:4060078976 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 2019-05-12 20:08:54.618 T:4060078976 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 2019-05-12 20:08:54.618 T:4060078976 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 2019-05-12 20:08:54.618 T:4060078976 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR 2019-05-12 20:08:54.619 T:4060078976 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: none) 2019-05-12 20:08:54.619 T:4060078976 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 2019-05-12 20:08:54.619 T:4060078976 DEBUG: Output Device : I2S 2019-05-12 20:08:54.619 T:4060078976 DEBUG: Sample Rate : 48000 2019-05-12 20:08:54.619 T:4060078976 DEBUG: Sample Format : AE_FMT_S24NE4 2019-05-12 20:08:54.619 T:4060078976 DEBUG: Channel Count : 2 2019-05-12 20:08:54.619 T:4060078976 DEBUG: Channel Layout: FL, FR 2019-05-12 20:08:54.619 T:4060078976 DEBUG: Frames : 2400 2019-05-12 20:08:54.619 T:4060078976 DEBUG: Frame Size : 8 2019-05-12 20:08:54.622 T:4068471680 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-05-12 20:08:54.631 T:3607077760 DEBUG: CVideoPlayer::HandleMessages - player started 1 2019-05-12 20:08:54.631 T:3615470464 DEBUG: OnAVChange: CApplication::OnAVChange 2019-05-12 20:08:54.631 T:3607077760 DEBUG: VideoPlayer::Sync - Audio - pts: 288000.000000, cache: 514586.344785, totalcache: 800000.011921 2019-05-12 20:08:54.631 T:3607077760 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 2019-05-12 20:08:54.631 T:3607077760 DEBUG: CVideoPlayer::SetCaching - caching state 0 2019-05-12 20:08:54.632 T:3649041280 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-226586.344785) 2019-05-12 20:08:54.632 T:3640648576 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-226586.344785), level: 71, cache: 508802.010785 2019-05-12 20:08:54.632 T:3607077760 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 2019-05-12 20:08:54.632 T:3640648576 DEBUG: CDVDAudio::Resume - resume audio stream 2019-05-12 20:08:54.632 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc 2019-05-12 20:08:54.632 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange 2019-05-12 20:08:54.635 T:3615470464 DEBUG: OnAVStarted: CApplication::OnAVStarted 2019-05-12 20:08:54.636 T:4068471680 DEBUG: ActiveAE - start sync of audio stream 2019-05-12 20:08:54.636 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc 2019-05-12 20:08:54.636 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart 2019-05-12 20:08:54.641 T:4090081856 DEBUG: Activating window ID: 12005 2019-05-12 20:08:54.642 T:4090081856 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 2019-05-12 20:08:54.642 T:4090081856 DEBUG: FreeVisualisation() done 2019-05-12 20:08:54.644 T:4090081856 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 2019-05-12 20:08:54.656 T:4090081856 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 2019-05-12 20:08:54.657 T:3615470464 DEBUG: OnAVChange: CApplication::OnAVChange 2019-05-12 20:08:54.657 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc 2019-05-12 20:08:54.658 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange 2019-05-12 20:08:54.770 T:4068471680 DEBUG: ActiveAE::SyncStream - average error of 21.179955, start adjusting 2019-05-12 20:08:54.771 T:4068471680 DEBUG: ActiveAE::SyncStream - average error 0.179955 below threshold of 30.000000 2019-05-12 20:08:55.821 T:3640648576 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-33280.426731, adjusted:-33280.426731 2019-05-12 20:08:59.708 T:3649041280 DEBUG: CPtsTracker: detected pattern of length 1: 41708.33, frameduration: 41708.333333 2019-05-12 20:09:24.657 T:3615470464 DEBUG: Thread JobWorker 3615470464 terminating (autodelete) 2019-05-12 20:09:27.556 T:4090081856 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 2019-05-12 20:09:27.556 T:4090081856 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 2019-05-12 20:09:27.569 T:4090081856 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 2019-05-12 20:09:27.569 T:4090081856 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 2019-05-12 20:09:27.576 T:4090081856 NOTICE: CVideoPlayer::CloseFile() 2019-05-12 20:09:27.576 T:4090081856 DEBUG: DeleteRenderer - deleting renderer 2019-05-12 20:09:27.576 T:4090081856 NOTICE: VideoPlayer: waiting for threads to exit 2019-05-12 20:09:27.583 T:3607077760 NOTICE: CVideoPlayer::OnExit() 2019-05-12 20:09:27.583 T:3607077760 DEBUG: CVideoPlayer: updating file item stream details with current streams 2019-05-12 20:09:27.583 T:3607077760 NOTICE: Closing stream player 1 2019-05-12 20:09:27.583 T:3607077760 NOTICE: Waiting for audio thread to exit 2019-05-12 20:09:27.621 T:3640648576 NOTICE: thread end: CVideoPlayerAudio::OnExit() 2019-05-12 20:09:27.621 T:3640648576 DEBUG: Thread VideoPlayerAudio 3640648576 terminating 2019-05-12 20:09:27.621 T:3607077760 NOTICE: Closing audio device 2019-05-12 20:09:27.670 T:3607077760 DEBUG: CDVDAudio::Flush - flush audio stream 2019-05-12 20:09:27.671 T:4068471680 DEBUG: CActiveAE::DiscardStream - audio stream deleted 2019-05-12 20:09:27.671 T:4068471680 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-05-12 20:09:27.672 T:3607077760 DEBUG: Previous line repeats 2 times. 2019-05-12 20:09:27.673 T:3607077760 NOTICE: Deleting audio codec 2019-05-12 20:09:27.673 T:4060078976 INFO: CActiveAESink::OpenSink - initialize sink 2019-05-12 20:09:27.673 T:3607077760 NOTICE: Closing stream player 2 2019-05-12 20:09:27.673 T:3607077760 NOTICE: waiting for video thread to exit 2019-05-12 20:09:27.691 T:3649041280 NOTICE: thread end: video_thread 2019-05-12 20:09:27.691 T:3649041280 DEBUG: Thread VideoPlayerVideo 3649041280 terminating 2019-05-12 20:09:27.691 T:3607077760 NOTICE: deleting video codec 2019-05-12 20:09:27.692 T:3649041280 DEBUG: Thread JobWorker start, auto delete: true 2019-05-12 20:09:27.692 T:3607077760 DEBUG: CSMBFile::Close closing fd 10000 2019-05-12 20:09:27.696 T:4090081856 NOTICE: VideoPlayer: finished waiting 2019-05-12 20:09:27.696 T:3607077760 DEBUG: Thread VideoPlayer 3607077760 terminating 2019-05-12 20:09:27.696 T:4090081856 NOTICE: CVideoPlayer::CloseFile() 2019-05-12 20:09:27.696 T:4090081856 NOTICE: VideoPlayer: waiting for threads to exit 2019-05-12 20:09:27.696 T:4090081856 NOTICE: VideoPlayer: finished waiting 2019-05-12 20:09:27.698 T:4090081856 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 2019-05-12 20:09:27.704 T:3649041280 INFO: Deleting settings information for files 2019-05-12 20:09:27.707 T:3607077760 DEBUG: Thread JobWorker start, auto delete: true 2019-05-12 20:09:27.707 T:3649041280 DEBUG: DoWork - Saving file state for video item 2019-05-12 20:09:27.728 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 2019-05-12 20:09:27.728 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 2019-05-12 20:09:27.745 T:3607077760 DEBUG: OnPlayBackStopped: CApplication::OnPlayBackStopped 2019-05-12 20:09:27.746 T:4090069888 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 2019-05-12 20:09:27.746 T:4090069888 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 2019-05-12 20:09:27.885 T:4060078976 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=I2S,DEV=0 2019-05-12 20:09:27.885 T:4060078976 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=I2S,DEV=0" 2019-05-12 20:09:28.108 T:4060078976 INFO: CAESinkALSA::Initialize - Opened device "sysdefault:CARD=I2S" 2019-05-12 20:09:28.109 T:4060078976 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2019-05-12 20:09:28.109 T:4060078976 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4 2019-05-12 20:09:28.109 T:4060078976 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 2019-05-12 20:09:28.111 T:4060078976 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 2019-05-12 20:09:28.111 T:4060078976 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 2019-05-12 20:09:28.111 T:4060078976 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 2019-05-12 20:09:28.111 T:4060078976 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR 2019-05-12 20:09:28.111 T:4060078976 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: none) 2019-05-12 20:09:28.111 T:4060078976 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 2019-05-12 20:09:28.111 T:4060078976 DEBUG: Output Device : I2S 2019-05-12 20:09:28.111 T:4060078976 DEBUG: Sample Rate : 48000 2019-05-12 20:09:28.111 T:4060078976 DEBUG: Sample Format : AE_FMT_S24NE4 2019-05-12 20:09:28.111 T:4060078976 DEBUG: Channel Count : 2 2019-05-12 20:09:28.111 T:4060078976 DEBUG: Channel Layout: FL, FR 2019-05-12 20:09:28.111 T:4060078976 DEBUG: Frames : 2400 2019-05-12 20:09:28.111 T:4060078976 DEBUG: Frame Size : 8 2019-05-12 20:09:28.113 T:4068471680 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-05-12 20:09:28.142 T:4090081856 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 109 2019-05-12 20:09:28.142 T:4090081856 DEBUG: CDRMUtils::DrmFbGetFromBo - using modifier: 0 2019-05-12 20:09:28.201 T:4090081856 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 76 2019-05-12 20:09:28.201 T:4090081856 DEBUG: CDRMUtils::DrmFbGetFromBo - using modifier: 0 2019-05-12 20:09:29.901 T:4090081856 NOTICE: Disabled debug logging due to GUI setting. Level 0. 2019-05-12 20:09:29.901 T:4090081856 NOTICE: Log level changed to "LOG_LEVEL_NORMAL"