2013-12-14, 23:01
(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.Code:* - nice -1
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