(2013-12-14, 22:26)popcornmix Wrote: Yes, that is unnecessary. Removed now,
Thanks, I've uploaded a new build #1412b (see
post #1894) with this reduced logging.
(2013-12-14, 22:31)popcornmix Wrote: No, but we did find a number of cases where high cpu caused underrun. You may see "underrun" in log if Pi Sink underruns, but you also get silence inserted when engine underruns without a message.
One issue is that the Sink thread is meant to run at higher (nice=-1) priority than other threads but that may not be allowed.
On raspbian I had to insert
/etc/security/limits.conf for this request to have any effect.
In OpenELEC I'm not sure if processes are allowed to decrease their nice level.
Not sure about decreasing the nice level in OpenELEC, though as everything runs as root I don't see why you shouldn't be able to do that.
In case its useful, this is the debug that appears when one Flac track ends and the next begins - there is a brief silence during the transition (CPU load averages about 25% at this time):
Code:
21:03:15 706.736511 T:2716849232 DEBUG: OnQueueNextItem : play state was 2, starting 0
21:03:15 706.779358 T:2884629584 NOTICE: Thread JobWorker start, auto delete: true
21:03:15 706.786560 T:2884629584 DEBUG: CFileCache::Open - opening <artists/65/48/547.flac> using cache
21:03:15 706.818481 T:2884629584 DEBUG: NFS: Refreshing context for 192.168.0.3/mnt/share, old: 521706, new: 693586
21:03:15 706.818726 T:2884629584 DEBUG: NFS: Using cached context.
21:03:15 706.825073 T:2884629584 DEBUG: CNFSFile::Open - opened mnt/share/data/Music/MP3/Daft Punk/Alive 2007/11 Daft Punk - Da Funk, Daftendirekt.flac
21:03:15 706.967773 T:2867852368 NOTICE: Thread FileCache start, auto delete: false
21:03:15 707.011047 T:2884629584 DEBUG: CNFSFile::Open - opened mnt/share/data/Music/MP3/Daft Punk/Alive 2007/11 Daft Punk - Da Funk, Daftendirekt.flac
21:03:16 707.112183 T:2884629584 DEBUG: unrecognized XipComment name: TRACKTOTAL
21:03:16 707.120178 T:2884629584 DEBUG: CNFSFile::Close closing file mnt/share/data/Music/MP3/Daft Punk/Alive 2007/11 Daft Punk - Da Funk, Daftendirekt.flac
21:03:16 707.411133 T:2884629584 INFO: AudioDecoder: File is queued
21:03:16 707.421021 T:2884629584 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 0.000000
21:03:16 707.439392 T:3034575952 DEBUG: CAESinkPi:IsCompatible Format:15 Channels:2 Samplerate:44100
21:03:16 707.481323 T:3034575952 INFO: CActiveAE::OpenSink - sink incompatible, re-starting
21:03:16 707.581360 T:3034575952 DEBUG: CAESinkPi:Drain delay:98ms now:0ms (0)
21:03:16 707.581604 T:3034575952 DEBUG: CAESinkPi:Deinitialize
21:03:16 707.590454 T:3034575952 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xa75df928
21:03:16 707.591125 T:3034575952 DEBUG: CActiveAE::OpenSink - trying to open device SinkPi:Pi:HDMI
21:03:16 707.591492 T:3034575952 DEBUG: CAESinkPi:Initialize Format:4 Channels:2 Samplerate:44100 framesize:4 bufsize:17640 bytes/s=176400.00
21:03:16 707.591675 T:3034575952 DEBUG: CActiveAE::OpenSink - SinkPi Initialized:
21:03:16 707.591858 T:3034575952 DEBUG: Output Device : Device not found
21:03:16 707.591980 T:3034575952 DEBUG: Sample Rate : 44100
21:03:16 707.592224 T:3034575952 DEBUG: Sample Format : AE_FMT_S16NE
21:03:16 707.592407 T:3034575952 DEBUG: Channel Count : 2
21:03:16 707.592651 T:3034575952 DEBUG: Channel Layout: FL,FR
21:03:16 707.592773 T:3034575952 DEBUG: Frames : 2205
21:03:16 707.592957 T:3034575952 DEBUG: Frame Samples : 2
21:03:16 707.593140 T:3034575952 DEBUG: Frame Size : 4
21:03:16 707.621033 T:3044508752 DEBUG: DllAvUtilBase: Using libavutil system library
21:03:16 707.621338 T:3044508752 DEBUG: DllAvFormat: Using libswresample system library
21:03:16 707.622192 T:3044508752 DEBUG: DllAvUtilBase: Using libavutil system library
21:03:16 707.622437 T:3044508752 DEBUG: DllAvFormat: Using libswresample system library
21:03:16 707.625732 T:3034575952 DEBUG: CAESinkPi:Initialize2
21:03:16 707.629333 T:3034575952 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xa63af9c8
21:03:16 707.630371 T:3044508752 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
21:03:16 707.638000 T:3034575952 DEBUG: Previous line repeats 1 times.
21:03:16 707.638000 T:3034575952 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(8820), nBufferAlignmen(16)
21:03:16 707.643738 T:3044508752 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
21:03:16 707.670044 T:2884629584 INFO: PAPlayer::PrepareStream - Ready
21:03:20 711.502136 T:2716849232 DEBUG: OnPlayBackStarted : play state was 2, starting 0
21:03:20 711.506470 T:2751456336 DEBUG: Thread FileCache 2751456336 terminating
21:03:20 711.514648 T:2716849232 DEBUG: CNFSFile::Close closing file mnt/share/data/Music/MP3/Daft Punk/Alive 2007/10 Daft Punk - Prime Time of Your Life, Brainwasher, Rollin' And Scratchin',Alive.flac
21:03:20 711.518250 T:3044520784 DEBUG: CGUIInfoManager::SetCurrentSong(musicdb://artists/65/48/547.flac?albumartistsonly=true&albumid=48&artistid=65)
21:03:20 711.800110 T:2716849232 DEBUG: PAPlayer::ProcessStreams - Stream Freed
21:03:20 711.800476 T:3044508752 DEBUG: CActiveAE::DiscardStream - audio stream deleted
21:03:20 711.800842 T:3044508752 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
21:03:20 711.816345 T:3044520784 DEBUG: Previous line repeats 1 times.
21:03:20 711.816345 T:3044520784 DEBUG: Loading additional tag info for file nfs://192.168.0.3/mnt/share/data/Music/MP3/Daft Punk/Alive 2007/11 Daft Punk - Da Funk, Daftendirekt.flac
21:03:20 711.823181 T:3044520784 DEBUG: CNFSFile::Open - opened mnt/share/data/Music/MP3/Daft Punk/Alive 2007/11 Daft Punk - Da Funk, Daftendirekt.flac
21:03:20 711.854919 T:3044520784 DEBUG: unrecognized XipComment name: TRACKTOTAL
21:03:20 711.855347 T:3044520784 DEBUG: CNFSFile::Close closing file mnt/share/data/Music/MP3/Daft Punk/Alive 2007/11 Daft Punk - Da Funk, Daftendirekt.flac
21:03:20 711.855774 T:3044520784 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
21:03:20 711.856079 T:3044520784 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
21:03:20 711.860413 T:2884629584 DEBUG: DoWork - Saving file state for audio item musicdb://artists/65/48/546.flac?albumartistsonly=true&albumid=48&artistid=65
21:03:20 711.875366 T:2884629584 DEBUG: DoWork - Marking audio item musicdb://artists/65/48/546.flac?albumartistsonly=true&albumid=48&artistid=65 as listened
21:03:20 711.897949 T:2884629584 DEBUG: Mysql execute: UPDATE song SET iTimesPlayed=iTimesPlayed+1, lastplayed=CURRENT_TIMESTAMP where idSong=546