Mixxx

Bug #1404401
Comment #27

Comment 27 for bug 1404401

Revision history for this message

Daniel Schürmann (daschuer) wrote on 2014-12-26:

#27

An Yes, we need RJs patch

I get heavy load if I play a track in one deck using Linkwitz-Riley EQ and turn Gain the to zero.
With the patch, there is no load change when turning to Zero.
I can see similar results on I5 Notebook x64 with small Audiobuffers.

Enabling DAZ helps. I do not have a clue why this happens on a SSE2 build? According to the test above this does not happen ...
So there seams to be an other issue.

Debug [Main]: =====================================
Debug [Main]: BASE STATS
Debug [Main]: =====================================
Debug [Main]: Stat("AnalyserQueue process","count=1")
Debug [Main]: Stat("CachingReaderWorker [Channel1]","count=574")
Debug [Main]: Stat("CachingReaderWorker [Channel2]","count=1")
Debug [Main]: Stat("CachingReaderWorker [PreviewDeck1]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler1]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler2]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler3]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler4]","count=1")
Debug [Main]: Stat("EngineBuffer::process_pauselock","count=2481,sum=1.97225e+08ns,average=79494.2ns,min=30311ns,max=1.09239e+06ns,variance=3.04507e+09ns^2,stddev=55182.2ns")
Debug [Main]: Stat("EngineMaster::mixChannels_0active","count=7443,sum=4.85571e+07ns,average=6523.86ns,min=3282ns,max=730540ns,variance=1.4778e+08ns^2,stddev=12156.5ns")
Debug [Main]: Stat("EngineMaster::mixChannels_1active","count=2481,sum=3.467e+07ns,average=13974.2ns,min=7333ns,max=833346ns,variance=6.11274e+08ns^2,stddev=24724ns")
Debug [Main]: Stat("EngineMaster::process","count=4962")
Debug [Main]: Stat("EngineMaster::processChannels","count=2480,sum=2.2165e+10ns,average=8.9375e+06ns,min=960248ns,max=5.28434e+07ns,variance=1.24014e+14ns^2,stddev=1.11361e+07ns")
Debug [Main]: Stat("EngineSideChain","count=191")
Debug [Main]: Stat("EngineSideChain::process","count=192")
Debug [Main]: Stat("EngineSideChain::writeSamples","count=4962")
Debug [Main]: Stat("EngineSideChain::writeSamples wake up","count=190")
Debug [Main]: Stat("EngineWorkerScheduler","count=572")
Debug [Main]: Stat("LinkwitzRiley8EQEffect","count=2480,sum=2.11762e+10ns,average=8.5388e+06ns,min=743949ns,max=5.25722e+07ns,variance=1.24491e+14ns^2,stddev=1.11575e+07ns")
Debug [Main]: Stat("MixxxMainWindow::~MixxxMainWindow","count=1,sum=1.13623e+09ns,average=1.13623e+09ns,min=1.13623e+09ns,max=1.13623e+09ns,variance=0ns^2,stddev=0ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess output 0, HDA Intel: ALC269 Analog (hw:0,0)","count=2481,sum=1.89776e+08ns,average=76491.7ns,min=22628ns,max=1.06907e+07ns,variance=2.21524e+11ns^2,stddev=470664ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess prepare 0, HDA Intel: ALC269 Analog (hw:0,0)","count=2480,sum=2.34364e+10ns,average=9.45015e+06ns,min=1.14386e+06ns,max=5.31427e+07ns,variance=1.24151e+14ns^2,stddev=1.11423e+07ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcessClkRef 0, HDA Intel: ALC269 Analog (hw:0,0)","count=4962")
Debug [Main]: Stat("VsyncThread real time error","count=17,sum=17,average=1,min=1,max=1,variance=0^2,stddev=0")
Debug [Main]: Stat("VsyncThread usleep for VSync","count=3990")
Debug [Main]: Stat("VsyncThread vsync render","count=4024")
Debug [Main]: Stat("VsyncThread vsync swap","count=4025")
Debug [Main]: Stat("WOverview::paintEvent","count=92,sum=4.11161e+07ns,average=446914ns,min=22768ns,max=885378ns,variance=3.54065e+10ns^2,stddev=188166ns")
Debug [Main]: Stat("WVuMeter::paintEvent","count=3565,sum=2.34213e+08ns,average=65697.9ns,min=37365ns,max=1.74631e+06ns,variance=1.82733e+09ns^2,stddev=42747.3ns")
Debug [Main]: Stat("WaveformWidgetFactory::render() 2waveforms","count=2012,sum=2.01004e+10ns,average=9.99028e+06ns,min=5.18285e+06ns,max=4.00311e+07ns,variance=8.70301e+12ns^2,stddev=2.95009e+06ns")
Debug [Main]: Stat("WaveformWidgetFactory::swap() 2waveforms","count=2012,sum=2.95026e+09ns,average=1.46633e+06ns,min=880349ns,max=1.21566e+07ns,variance=5.5925e+11ns^2,stddev=747830ns")
Debug [Main]: =====================================
Debug [Main]: EXPERIMENT STATS
Debug [Main]: =====================================
Debug [Main]: Stat("CachingReaderWorker [Channel1]","count=191")
Debug [Main]: Stat("EngineBuffer::process_pauselock","count=762,sum=6.66809e+07ns,average=87507.8ns,min=50635ns,max=1.92692e+06ns,variance=8.42527e+09ns^2,stddev=91789.2ns")
Debug [Main]: Stat("EngineMaster::mixChannels_0active","count=2289,sum=1.52183e+07ns,average=6648.44ns,min=3841ns,max=331048ns,variance=9.07444e+07ns^2,stddev=9525.98ns")
Debug [Main]: Stat("EngineMaster::mixChannels_1active","count=763,sum=1.0052e+07ns,average=13174.3ns,min=7333ns,max=181657ns,variance=5.79651e+07ns^2,stddev=7613.48ns")
Debug [Main]: Stat("EngineMaster::process","count=1525")
Debug [Main]: Stat("EngineMaster::processChannels","count=762,sum=1.24482e+09ns,average=1.63363e+06ns,min=954591ns,max=5.73739e+06ns,variance=4.66768e+11ns^2,stddev=683204ns")
Debug [Main]: Stat("EngineSideChain","count=58")
Debug [Main]: Stat("EngineSideChain::process","count=58")
Debug [Main]: Stat("EngineSideChain::writeSamples","count=1526")
Debug [Main]: Stat("EngineSideChain::writeSamples wake up","count=58")
Debug [Main]: Stat("EngineWorkerScheduler","count=192")
Debug [Main]: Stat("LinkwitzRiley8EQEffect","count=762,sum=9.44911e+08ns,average=1.24004e+06ns,min=744438ns,max=4.48667e+06ns,variance=3.10929e+11ns^2,stddev=557610ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess output 0, HDA Intel: ALC269 Analog (hw:0,0)","count=763,sum=9.56296e+07ns,average=125334ns,min=22769ns,max=9.20683e+06ns,variance=4.19177e+11ns^2,stddev=647439ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess prepare 0, HDA Intel: ALC269 Analog (hw:0,0)","count=762,sum=1.61254e+09ns,average=2.11619e+06ns,min=1.15643e+06ns,max=1.59874e+07ns,variance=1.52761e+12ns^2,stddev=1.23596e+06ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcessClkRef 0, HDA Intel: ALC269 Analog (hw:0,0)","count=1525")
Debug [Main]: Stat("VsyncThread real time error","count=3,sum=3,average=1,min=1,max=1,variance=0^2,stddev=0")
Debug [Main]: Stat("VsyncThread usleep for VSync","count=1056")
Debug [Main]: Stat("VsyncThread vsync render","count=1062")
Debug [Main]: Stat("VsyncThread vsync swap","count=1062")
Debug [Main]: Stat("WOverview::paintEvent","count=28,sum=1.42161e+07ns,average=507719ns,min=21930ns,max=1.01961e+06ns,variance=6.31043e+10ns^2,stddev=251206ns")
Debug [Main]: Stat("WVuMeter::paintEvent","count=698,sum=4.3623e+07ns,average=62497.1ns,min=38273ns,max=285162ns,variance=5.41059e+08ns^2,stddev=23260.7ns")
Debug [Main]: Stat("WaveformWidgetFactory::render() 2waveforms","count=531,sum=5.20469e+09ns,average=9.80168e+06ns,min=5.27043e+06ns,max=2.03392e+07ns,variance=5.95697e+12ns^2,stddev=2.44069e+06ns")
Debug [Main]: Stat("WaveformWidgetFactory::swap() 2waveforms","count=531,sum=7.55607e+08ns,average=1.42299e+06ns,min=884540ns,max=7.48203e+06ns,variance=4.76785e+11ns^2,stddev=690496ns")
Debug [Main]: =====================================
Debug [Main]: Mixxx shutdown complete with code 0

An Yes, we need RJs patch

I get heavy load if I play a track in one deck using Linkwitz-Riley EQ and turn Gain the to zero.
With the patch, there is no load change when turning to Zero. 
I can see similar results on I5 Notebook x64 with small Audiobuffers.

Enabling DAZ helps. I do not have a clue why this happens on a SSE2 build? According to the test above this does not happen ...
So there seams to be an other issue.

Debug [Main]: ===================================== 
Debug [Main]: BASE STATS 
Debug [Main]: ===================================== 
Debug [Main]: Stat("AnalyserQueue process","count=1")
Debug [Main]: Stat("CachingReaderWorker [Channel1]","count=574")
Debug [Main]: Stat("CachingReaderWorker [Channel2]","count=1")
Debug [Main]: Stat("CachingReaderWorker [PreviewDeck1]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler1]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler2]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler3]","count=1")
Debug [Main]: Stat("CachingReaderWorker [Sampler4]","count=1")
Debug [Main]: Stat("EngineBuffer::process_pauselock","count=2481,sum=1.97225e+08ns,average=79494.2ns,min=30311ns,max=1.09239e+06ns,variance=3.04507e+09ns^2,stddev=55182.2ns")
Debug [Main]: Stat("EngineMaster::mixChannels_0active","count=7443,sum=4.85571e+07ns,average=6523.86ns,min=3282ns,max=730540ns,variance=1.4778e+08ns^2,stddev=12156.5ns")
Debug [Main]: Stat("EngineMaster::mixChannels_1active","count=2481,sum=3.467e+07ns,average=13974.2ns,min=7333ns,max=833346ns,variance=6.11274e+08ns^2,stddev=24724ns")
Debug [Main]: Stat("EngineMaster::process","count=4962")
Debug [Main]: Stat("EngineMaster::processChannels","count=2480,sum=2.2165e+10ns,average=8.9375e+06ns,min=960248ns,max=5.28434e+07ns,variance=1.24014e+14ns^2,stddev=1.11361e+07ns")
Debug [Main]: Stat("EngineSideChain","count=191")
Debug [Main]: Stat("EngineSideChain::process","count=192")
Debug [Main]: Stat("EngineSideChain::writeSamples","count=4962")
Debug [Main]: Stat("EngineSideChain::writeSamples wake up","count=190")
Debug [Main]: Stat("EngineWorkerScheduler","count=572")
Debug [Main]: Stat("LinkwitzRiley8EQEffect","count=2480,sum=2.11762e+10ns,average=8.5388e+06ns,min=743949ns,max=5.25722e+07ns,variance=1.24491e+14ns^2,stddev=1.11575e+07ns")
Debug [Main]: Stat("MixxxMainWindow::~MixxxMainWindow","count=1,sum=1.13623e+09ns,average=1.13623e+09ns,min=1.13623e+09ns,max=1.13623e+09ns,variance=0ns^2,stddev=0ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess output 0, HDA Intel: ALC269 Analog (hw:0,0)","count=2481,sum=1.89776e+08ns,average=76491.7ns,min=22628ns,max=1.06907e+07ns,variance=2.21524e+11ns^2,stddev=470664ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess prepare 0, HDA Intel: ALC269 Analog (hw:0,0)","count=2480,sum=2.34364e+10ns,average=9.45015e+06ns,min=1.14386e+06ns,max=5.31427e+07ns,variance=1.24151e+14ns^2,stddev=1.11423e+07ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcessClkRef 0, HDA Intel: ALC269 Analog (hw:0,0)","count=4962")
Debug [Main]: Stat("VsyncThread real time error","count=17,sum=17,average=1,min=1,max=1,variance=0^2,stddev=0")
Debug [Main]: Stat("VsyncThread usleep for VSync","count=3990")
Debug [Main]: Stat("VsyncThread vsync render","count=4024")
Debug [Main]: Stat("VsyncThread vsync swap","count=4025")
Debug [Main]: Stat("WOverview::paintEvent","count=92,sum=4.11161e+07ns,average=446914ns,min=22768ns,max=885378ns,variance=3.54065e+10ns^2,stddev=188166ns")
Debug [Main]: Stat("WVuMeter::paintEvent","count=3565,sum=2.34213e+08ns,average=65697.9ns,min=37365ns,max=1.74631e+06ns,variance=1.82733e+09ns^2,stddev=42747.3ns")
Debug [Main]: Stat("WaveformWidgetFactory::render() 2waveforms","count=2012,sum=2.01004e+10ns,average=9.99028e+06ns,min=5.18285e+06ns,max=4.00311e+07ns,variance=8.70301e+12ns^2,stddev=2.95009e+06ns")
Debug [Main]: Stat("WaveformWidgetFactory::swap() 2waveforms","count=2012,sum=2.95026e+09ns,average=1.46633e+06ns,min=880349ns,max=1.21566e+07ns,variance=5.5925e+11ns^2,stddev=747830ns")
Debug [Main]: ===================================== 
Debug [Main]: EXPERIMENT STATS 
Debug [Main]: ===================================== 
Debug [Main]: Stat("CachingReaderWorker [Channel1]","count=191")
Debug [Main]: Stat("EngineBuffer::process_pauselock","count=762,sum=6.66809e+07ns,average=87507.8ns,min=50635ns,max=1.92692e+06ns,variance=8.42527e+09ns^2,stddev=91789.2ns")
Debug [Main]: Stat("EngineMaster::mixChannels_0active","count=2289,sum=1.52183e+07ns,average=6648.44ns,min=3841ns,max=331048ns,variance=9.07444e+07ns^2,stddev=9525.98ns")
Debug [Main]: Stat("EngineMaster::mixChannels_1active","count=763,sum=1.0052e+07ns,average=13174.3ns,min=7333ns,max=181657ns,variance=5.79651e+07ns^2,stddev=7613.48ns")
Debug [Main]: Stat("EngineMaster::process","count=1525")
Debug [Main]: Stat("EngineMaster::processChannels","count=762,sum=1.24482e+09ns,average=1.63363e+06ns,min=954591ns,max=5.73739e+06ns,variance=4.66768e+11ns^2,stddev=683204ns")
Debug [Main]: Stat("EngineSideChain","count=58")
Debug [Main]: Stat("EngineSideChain::process","count=58")
Debug [Main]: Stat("EngineSideChain::writeSamples","count=1526")
Debug [Main]: Stat("EngineSideChain::writeSamples wake up","count=58")
Debug [Main]: Stat("EngineWorkerScheduler","count=192")
Debug [Main]: Stat("LinkwitzRiley8EQEffect","count=762,sum=9.44911e+08ns,average=1.24004e+06ns,min=744438ns,max=4.48667e+06ns,variance=3.10929e+11ns^2,stddev=557610ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess output 0, HDA Intel: ALC269 Analog (hw:0,0)","count=763,sum=9.56296e+07ns,average=125334ns,min=22769ns,max=9.20683e+06ns,variance=4.19177e+11ns^2,stddev=647439ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcess prepare 0, HDA Intel: ALC269 Analog (hw:0,0)","count=762,sum=1.61254e+09ns,average=2.11619e+06ns,min=1.15643e+06ns,max=1.59874e+07ns,variance=1.52761e+12ns^2,stddev=1.23596e+06ns")
Debug [Main]: Stat("SoundDevicePortAudio::callbackProcessClkRef 0, HDA Intel: ALC269 Analog (hw:0,0)","count=1525")
Debug [Main]: Stat("VsyncThread real time error","count=3,sum=3,average=1,min=1,max=1,variance=0^2,stddev=0")
Debug [Main]: Stat("VsyncThread usleep for VSync","count=1056")
Debug [Main]: Stat("VsyncThread vsync render","count=1062")
Debug [Main]: Stat("VsyncThread vsync swap","count=1062")
Debug [Main]: Stat("WOverview::paintEvent","count=28,sum=1.42161e+07ns,average=507719ns,min=21930ns,max=1.01961e+06ns,variance=6.31043e+10ns^2,stddev=251206ns")
Debug [Main]: Stat("WVuMeter::paintEvent","count=698,sum=4.3623e+07ns,average=62497.1ns,min=38273ns,max=285162ns,variance=5.41059e+08ns^2,stddev=23260.7ns")
Debug [Main]: Stat("WaveformWidgetFactory::render() 2waveforms","count=531,sum=5.20469e+09ns,average=9.80168e+06ns,min=5.27043e+06ns,max=2.03392e+07ns,variance=5.95697e+12ns^2,stddev=2.44069e+06ns")
Debug [Main]: Stat("WaveformWidgetFactory::swap() 2waveforms","count=531,sum=7.55607e+08ns,average=1.42299e+06ns,min=884540ns,max=7.48203e+06ns,variance=4.76785e+11ns^2,stddev=690496ns")
Debug [Main]: ===================================== 
Debug [Main]: Mixxx shutdown complete with code 0