Bugzilla – Bug 9684
SC 7.3 on ReadyNAS: Both rescan and wipecache are slower than in past builds.
Last modified: 2011-01-14 00:51:29 UTC
Sys Info: ReadyNAS DUO with 1559 songs. Steps to Reproduce: 1. Update SC in ReadyNAS to SqueezeCenter_7.3~23486.bin. 2. Make sure there are 1559 songs in the music folder. 3. Run the cli rescan command. 4. Notice this command takes 1578 seconds to finish. In most past SC 7.3 builds, it would only take abut 260 seconds. (The max had been set at 300 seconds based on performance benchmark tests conducted by this user.) 5. After the rescan command finishes running, run the wipecache command. 6. Notice this command also takes more time than expected, though not by much. 7. With SqueezeCenter_7.3~23486.bin, it took 1826 seconds. 8. In the past, the same command took about 1600 seconds. The max is set at 1800 seconds. 9. Please see this web page for the result: http://eng.slimdevices.com:80/~wallace/SqueezeCenter_7.3~23486.bin_From_7.3_TestsLogsOnReadyNASOnSparc/PerformanceTestsLog.html 10. Please see this web page to for result of a past test: http://eng.slimdevices.com/~wallace/SqueezeCenter_7.3~23428.bin_From_7.3_TestsLogsOnReadyNASOnSparc/PerformanceTestsLog.html Note: The tests will be re-run to see if this bug is reproducible.
Created attachment 4123 [details] scanner.log for this bug.
Created attachment 4124 [details] server log for this bug.
On second run, the tests passed. Rescan took only 263 seconds and wipecache took 1735 seconds. Will wait for tomorrow's tets result before changing the status. By the way, it took more than 10 minutes for ReadyNAs to download the 7.3 SC build this afternoon. Usually, it took only about 1 minute.
With today's build, SqueezeCenter_7.3~23501.bin, the rescan and wipecache are once again too slow compared to the past. Rescan took 1516 seconds. It used to take less than 300. Wipecache took 1872 seconds. It used to take about 1600. Please check this web page for more info: http://eng.slimdevices.com:80/~wallace/SqueezeCenter_7.3~23501.bin_From_7.3_TestsLogsOnReadyNASOnSparc/PerformanceTestsLog.html
Steven notes the ReadyNAS might have some other process running. Can you run the 'top' command to see if anything else is going on? Andy notes that you should also check "Settings -> advanced -> performance to make sure artwork to is set to fast resizing and that pre-caching is disabled on all systems. This will make sure you are working from the same baseline.
Wallace: please work with Steven on this one. If you see the performance change dramatically, this may be an issue.
It has gotten worse. With SqueezeCenter_7.3.1~24367-sparc-readynas.bin, on the same ReadyNAS with the same number of songs, wipescan took 1904 seconds. Here is the link to the scanner log: http://eng.slimdevices.com/AutoTests/SqueezeCenter_7.3.1~24367-sparc-readynas.bin_From_7.3_TestsLogsOnreadynasOnsparc/scanner.log And here is the server log: http://eng.slimdevices.com/AutoTests/SqueezeCenter_7.3.1~24367-sparc-readynas.bin_From_7.3_TestsLogsOnreadynasOnsparc/server.log
Today's build, SqueezeCenter_7.3.1~24372-sparc-readynas.bin, is a little bit worse than yesterday's build. The wipecache command took 1967 seconds to finish. And here are the links to the logs files: http://eng.slimdevices.com/AutoTests/SqueezeCenter_7.3.1~24372-sparc-readynas.bin_From_7.3_TestsLogsOnreadynasOnsparc/scanner.log http://eng.slimdevices.com/AutoTests/SqueezeCenter_7.3.1~24372-sparc-readynas.bin_From_7.3_TestsLogsOnreadynasOnsparc/server.log
*** Bug 10555 has been marked as a duplicate of this bug. ***
For the last two builds, SqueezeCenter_7.4~24549-sparc-readynas.bin and SqueezeCenter_7.4~24586-sparc-readynas.bin, rescan had passed. wipecache is still failing. Hereis one of the scanner log: http://eng.slimdevices.com/~wallace/SqueezeCenter_7.4~24586-sparc-readynas.bin_From_7.4_TestsLogsOnreadynasOnsparc/scanner.log