[slim] Re: 6.5.0 Problem
I reduced this to 20 and - so far - everything appears to be working fine. Hopefully this will continue tomorrow... Thanks again! -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
It definitely seems to return to normal if I remove Dynamic Playlists and Trackstat from the server - not that I want to! I have only had this problem since upgrading from 6.3.1, though as I said earlier it seemed OK on the final beta 6.5 too but I may not have tested thoroughly enough. I'll just try reducing "NUMBER OF SONGS/ALBUMS/ARTISTS TO USE IN DYNAMIC PLAYLISTS" and see what effect that has. Thanks both for your help! -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
I'm not sure if a downgrade to 6.3.1 solves the problem. It would be a bit strange if it did, because the MySQL database in 6.5 should be faster than the SQLite database that was used in 6.3. If it also affects Search and Now Playing it would be interesting to se a log with DynamicPlaylist debuggin enabled when this happens. When you skip to next track there is DynamicPlayList/TrackStat code executed, but when you use Now Playing and Search no code in DynamicPlayList/TrackStat should execute. Of course it could be that now playing is slov when slimserver reaches the end of the track, this will actually happen a bit before you hear the track change since there is buffering involved. I think the actual track switch can be about 30 seconds before you hear it switch due to buffering. The buffering time might vary depending on a number of parameters, for example network speed between the SqueezeBox and slimserver computer. I have a feeling the hardware isn't your problem, it shouldn't be a problem handling a 3 rows table with that hardware. You might want to try to lower the setting in TrackStat that says: "NUMBER OF SONGS/ALBUMS/ARTISTS TO USE IN DYNAMIC PLAYLISTS" >From the logs it looks like it is set to 50 now but if you lower it to 10 or 20 you might get better performance. I'll try to optimize the algoritm in TrackStat in one of the next TrackStat releases, today it's really bad regarding performance as I mentioned in my previous post. -- erland Erland Isaksson 'My homepage' (http://erland.homeip.net) 'My download page' (http://erland.homeip.net/download) (Developer of 'TrackStat' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-trackstat) , 'SQLPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-sqlplaylist) , 'DynamicPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-dynamicplaylist), 'Custom Browse' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-custombrowse) and 'RandomPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-randomplaylist) plugins) erland's Profile: http://forums.slimdevices.com/member.php?userid=3124 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
Note that 6.5 has some extra code in the server to discard key presses which are more than 3 seconds old - this probably explains the need to press the button multiple times. If you remove DynamicPlayist/TrackStat do you get back to normal performance? -- Triode Triode's Profile: http://forums.slimdevices.com/member.php?userid=17 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
It's not so much a delay as just not happening - I might have to press a key 3 or 4 times before getting a response. I have noticed that it is not just track skipping that locks up - sometimes even Now Playing or Search (for example) become unresponsive. My PC is as follows: AMD Athlon 64 3500+ 1 GB PC3200 RAM 250Gb Seagate Barracuda SATA HD's I do have rather a lot of MP3's though - about 3 - however this has never been a problem on previous incarnations of SlimServer. Do you think it could be worthwhile back tracking to 6.3.1? -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
MillmoorRon;143015 Wrote: > This is (the end) of my log file after starting a "not played songs" > dynamic playlist and trying (mostly unsuccessfully) to skip a few > tracks. > > Does this tell you anything? > It looks like it takes about 4 seconds for DynamicPlayList to retreive a new track for a TrackStat playlist. You are running one of the playlists that should be pretty fast, so there are probably other TrackStat playlists that takes a lot more than 4 seconds. TrackStat also retreives 50 tracks even though DynamicPlayList only needs one. The reason for this is that some random selection code is done in the perl code instead of int the SQL query. I think there is a lot of optimizing potential in this part of the code, it really looks like I didn't have performance in mind when I wrote it. I will put an entry on the TrackStat wish list to optimize this. All this probably means that if you click the "next track" button it will take about 4 seconds until you see any response and the new track starts to play. Is this how it feels ? MillmoorRon;143703 Wrote: > Out of interest, what kind of hardware problem might influence the > performance of SQL on my system? What hardware do you have ? How many tracks do you have in your library ? The performance can be affected by: - processor speed - memory size - harddisk speed It's impossible to say which component that is critical without further investigation/knowledge of the system. On my AMD XP2500+ with 1GB memory and around 3000 tracks in the library it takes less than a second to switch to next track. -- erland Erland Isaksson 'My homepage' (http://erland.homeip.net) 'My download page' (http://erland.homeip.net/download) (Developer of 'TrackStat' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-trackstat) , 'SQLPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-sqlplaylist) , 'DynamicPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-dynamicplaylist), 'Custom Browse' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-custombrowse) and 'RandomPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-randomplaylist) plugins) erland's Profile: http://forums.slimdevices.com/member.php?userid=3124 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
Out of interest, what kind of hardware problem might influence the performance of SQL on my system? -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
This is (the end) of my log file after starting a "not played songs" dynamic playlist and trying (mostly unsuccessfully) to skip a few tracks. Does this tell you anything? getNextDynamicPlayListTracks TrackStat: Got: notplayed, 1 TrackStat: Calling playlistfunction for notplayed TrackStat::Statistics: Executing: select tracks.url from tracks left join track_statistics on tracks.url = track_statistics.url where tracks.audio=1 and (tracks.playCount=0 or tracks.playCount is null) and (track_statistics.playCount=0 or track_statistics.playCount is null) order by track_statistics.playCount asc,tracks.playCount asc,rand() limit 50; TrackStat: Got 1 tracks DynamicPlayList: Find returned 1 items DynamicPlayList: Adding trackstat_notplayed: Gerbil, 17267 DynamicPlayList: Adding continuous trackstat_notplayed with 26 items Request Task > 0.5 : 3.96195Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 3.97487 IR Delay > 0.5 : 1.48178 Timer Late > 0.5 : 3.58290 IR Delay > 0.5 : 1.37667 Timer Late > 0.5 : 3.19251 IR Delay > 0.5 : 1.26997 Timer Late > 0.5 : 3.09931 Timer Late > 0.5 : 2.26230 TrackStat: Entering commandCallback65 TrackStat: Exiting commandCallback65 TrackStat: Entering commandCallback65 TrackStat: Exiting commandCallback65 TrackStat: Entering commandCallback65 TrackStat: Stopping timing file:///Z:/_Music/MP3/Sweet/New%20York%20Connection.mp3 TrackStat: Total elapsed time in seconds: 4.876646 TrackStat: Time actually played in track: 4.876646 TrackStat: "New York Connection" NOT played long enough: Played 4.876646; needed to play 5 seconds. TrackStat: Starting a new song TrackStat: Starting to time file:///Z:/_Music/MP3/Walt%20Mink/El%20Producto/02%20-%20Everything%20Worthwhile.mp3 TrackStat: Exiting commandCallback65 TrackStat: Entering commandCallback65 TrackStat: Exiting commandCallback65 DynamicPlayList: received command playlist newsong initiated by DynamicPlayList: while in mode: trackstat_notplayed, from SqueezeBox3 DynamicPlayList: playRandom called with type trackstat_notplayed DynamicPlayList: 8 songs remaining, songIndex = 17 DynamicPlayList: Get playlist: trackstat_notplayed DynamicPlayList: Starting random selection of 1 items for type: trackstat_notplayed DynamicPlayList: Get playlist: trackstat_notplayed DynamicPlayList: Calling: Plugins::TrackStat::Plugin with: trackstat_notplayed , 1 , 10 DynamicPlayList: Calling: Plugins::TrackStat::Plugin :: getNextDynamicPlayListTracks TrackStat: Got: notplayed, 1 TrackStat: Calling playlistfunction for notplayed TrackStat::Statistics: Executing: select tracks.url from tracks left join track_statistics on tracks.url = track_statistics.url where tracks.audio=1 and (tracks.playCount=0 or tracks.playCount is null) and (track_statistics.playCount=0 or track_statistics.playCount is null) order by track_statistics.playCount asc,tracks.playCount asc,rand() limit 50; TrackStat: Got 1 tracks DynamicPlayList: Find returned 1 items DynamicPlayList: Adding trackstat_notplayed: She's Hearing Voices, 14110 DynamicPlayList: Adding continuous trackstat_notplayed with 27 items Request Task > 0.5 : 3.57652Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 3.58914 Timer Late > 0.5 : 3.55705 Timer Late > 0.5 : 2.94344 Timer Late > 0.5 : 2.80082 TrackStat: Entering commandCallback65 TrackStat: Exiting commandCallback65 TrackStat: Entering commandCallback65 TrackStat: Exiting commandCallback65 DynamicPlayList: received command playlist newsong initiated by DynamicPlayList: while in mode: trackstat_notplayed, from SqueezeBox3 DynamicPlayList: playRandom called with type trackstat_notplayed DynamicPlayList: 9 songs remaining, songIndex = 17 DynamicPlayList: 9 items remaining so not adding new track DynamicPlayList: Adding continuous trackstat_notplayed with 27 items Web Page Build > 0.5 : 0.84615Page: playlist.html Select Task > 0.5 : 0.85739Slim::Web::HTTP::processHTTP -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
Could you turn on the debugging flag in the Dynamic Playlists setting page in the web ui ? This will make it possible to see a little bit more what actually happens when the DynamicPlaylist plugin handles the commands that take som time. If these commands is happening when the track is changed it might just be that the SQL that is executed takes some time on your hardware. -- erland Erland Isaksson 'My homepage' (http://erland.homeip.net) 'My download page' (http://erland.homeip.net/download) (Developer of 'TrackStat' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-trackstat) , 'SQLPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-sqlplaylist) , 'DynamicPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-dynamicplaylist), 'Custom Browse' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-custombrowse) and 'RandomPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-randomplaylist) plugins) erland's Profile: http://forums.slimdevices.com/member.php?userid=3124 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
I have "Now Playing" displayed with just time remaining and no visual meter. -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
Unfortunately this can't tell what the freezes where on button presses at the start [do you remember what display mode you were in and what button you pressed?] The rest do look down to the plugin though. -- Triode Triode's Profile: http://forums.slimdevices.com/member.php?userid=17 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
I've taken away all 3rd party plugins except TrackStat and DynamicPlaylists. I then selected the "top rated songs" playlist and let it play for 20 minutes occasionally skipping forward to the next track. This is my resulting log file. Does this give you any pointers? - ERROR: objectForUrl: Null track request! Backtrace: frame 0: Slim::Schema::objectForUrl (E:\Data\SlimServer\server/Plugins/TrackStat/Storage.pm line 128) frame 1: Plugins::TrackStat::Storage::objectForUrl (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2963) frame 2: Plugins::TrackStat::Plugin::startTimingNewSong (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2677) frame 3: Plugins::TrackStat::Plugin::playCommand (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2905) frame 4: Plugins::TrackStat::Plugin::commandCallback65 (/PerlApp/Slim/Control/Request.pm line 1640) frame 5: Slim::Control::Request::notify (/PerlApp/Slim/Control/Request.pm line 753) frame 6: Slim::Control::Request::checkNotifications (slimserver.pl line 464) frame 7: main::idle (slimserver.pl line 35) frame 8: PerlSvc::Startup (perlsvc.pl line 1482) frame 9: PerlSvc::_startup (slimserver.pl line 0) frame 10: (eval) (slimserver.pl line 0) Request Task > 0.5 : 3.25968Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 3.26036Execute: Slim::Control::Commands::irCommand Response Time> 0.5 : 3.26107 Timer Late > 0.5 : 3.00613 IR Delay > 0.5 : 3.15981 Timer Late > 0.5 : 2.38494 Timer Late > 0.5 : 2.02233 Timer Late > 0.5 : 1.48748 Request Task > 0.5 : 0.81924Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 0.81998Execute: Slim::Control::Commands::irCommand Response Time> 0.5 : 0.82068 Timer Late > 0.5 : 0.56514 IR Delay > 0.5 : 0.71973 Request Task > 0.5 : 5.03623Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 5.03688Execute: Slim::Control::Commands::irCommand Response Time> 0.5 : 5.03758 Timer Late > 0.5 : 4.78747 IR Delay > 0.5 : 4.94383 Timer Late > 0.5 : 4.76897 Timer Late > 0.5 : 1.57814 Request Task > 0.5 : 3.06257Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 3.07919 Timer Late > 0.5 : 3.11985 Timer Late > 0.5 : 2.66999 Timer Late > 0.5 : 2.36254 Timer Late > 0.5 : 2.33153 Request Task > 0.5 : 3.12309Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 3.14030 Timer Late > 0.5 : 2.79418 Timer Late > 0.5 : 2.51324 Timer Late > 0.5 : 2.45550 Timer Late > 0.5 : 2.34949 Request Task > 0.5 : 2.95855Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.97534 IR Delay > 0.5 : 0.88187 Timer Late > 0.5 : 2.69880 IR Delay > 0.5 : 0.77545 Timer Late > 0.5 : 2.15884 Timer Late > 0.5 : 2.13993 Request Task > 0.5 : 2.96374Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.98071 Timer Late > 0.5 : 2.53498 Timer Late > 0.5 : 2.17766 Timer Late > 0.5 : 2.14748 Request Task > 0.5 : 3.12375Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 3.14226 Timer Late > 0.5 : 2.54094 IR Delay > 0.5 : 0.76996 Timer Late > 0.5 : 2.25631 IR Delay > 0.5 : 0.66800 -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
I think it happens during both SQL and Dynamic playlist use but I'll do a bit of playing and see if I can isolate exactly when it occurs. It seems to be at any point during a song. I didn't notice this happening in the week or so that I was running the final beta version, just since the full version was installed (clean). -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
Does this only happend when you are playing a playlist with the Dynamic Playlist plugin ? In that case, what kind of playlist are you playing ? SQLPlayList, TrackStat, RandomPlayList or some other ? Is the problem always at end of a track or does it also happen in middle or beginning of tracks ? -- erland Erland Isaksson 'My homepage' (http://erland.homeip.net) 'My download page' (http://erland.homeip.net/download) (Developer of 'TrackStat' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-trackstat) , 'SQLPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-sqlplaylist) , 'DynamicPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-dynamicplaylist), 'Custom Browse' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-custombrowse) and 'RandomPlayList' (http://erland.homeip.net/download/do/viewapplication?name=slimserver-randomplaylist) plugins) erland's Profile: http://forums.slimdevices.com/member.php?userid=3124 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
This suggests the majority of the pauses come from the Dynamic Playlist plugin. Could you try without it? -- Triode Triode's Profile: http://forums.slimdevices.com/member.php?userid=17 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
A bit more info: ERROR: Failed to detect server IP address. Invalid argument ERROR: objectForUrl: Null track request! Backtrace: frame 0: Slim::Schema::objectForUrl (E:\Data\SlimServer\server/Plugins/TrackStat/Storage.pm line 128) frame 1: Plugins::TrackStat::Storage::objectForUrl (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2963) frame 2: Plugins::TrackStat::Plugin::startTimingNewSong (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2677) frame 3: Plugins::TrackStat::Plugin::playCommand (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2905) frame 4: Plugins::TrackStat::Plugin::commandCallback65 (/PerlApp/Slim/Control/Request.pm line 1640) frame 5: Slim::Control::Request::notify (/PerlApp/Slim/Control/Request.pm line 753) frame 6: Slim::Control::Request::checkNotifications (slimserver.pl line 464) frame 7: main::idle (slimserver.pl line 35) frame 8: PerlSvc::Startup (perlsvc.pl line 1482) frame 9: PerlSvc::_startup (slimserver.pl line 0) frame 10: (eval) (slimserver.pl line 0) Timer Task > 0.5 : 0.56283 Plugins::LazySearch2::onFindTimer Response Time> 0.5 : 0.56302 Request Task > 0.5 : 0.54156Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 0.54225Execute: Slim::Control::Commands::irCommand Response Time> 0.5 : 0.54296 Request Task > 0.5 : 2.87201Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 2.87269Execute: Slim::Control::Commands::irCommand Response Time> 0.5 : 2.87340 Timer Late > 0.5 : 2.62038 Timer Late > 0.5 : 2.19853 Timer Late > 0.5 : 1.21976 Request Task > 0.5 : 2.29887Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.43785 Timer Late > 0.5 : 1.67452 Timer Late > 0.5 : 1.52748 Request Task > 0.5 : 2.48270Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.59810 Timer Late > 0.5 : 2.22875 Timer Late > 0.5 : 1.73911 Timer Late > 0.5 : 1.72874 Request Task > 0.5 : 2.31524Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.44339 Timer Late > 0.5 : 2.42638 Timer Late > 0.5 : 2.06636 Timer Late > 0.5 : 1.91238 Request Task > 0.5 : 2.51113Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.63540 Timer Late > 0.5 : 2.70331 Timer Late > 0.5 : 1.88466 Timer Late > 0.5 : 1.36758 Request Task > 0.5 : 0.56329Notify: Plugins::TrackStat::Plugin::commandCallback65 Response Time> 0.5 : 0.71692 Request Task > 0.5 : 6.99087Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 7.26327 Timer Late > 0.5 : 7.36683 Timer Late > 0.5 : 7.00764 Timer Late > 0.5 : 5.90682 Request Task > 0.5 : 0.82096Notify: Slim::Player::Playlist::modifyPlaylistCallback Request Task > 0.5 : 2.31407Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.43974 Timer Late > 0.5 : 2.07012 Timer Late > 0.5 : 1.95494 Timer Late > 0.5 : 1.43898 Request Task > 0.5 : 2.36760Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.47757 Timer Late > 0.5 : 2.12041 Timer Late > 0.5 : 1.71317 Request Task > 0.5 : 2.09371Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.09825 Timer Late > 0.5 : 1.67301 Timer Late > 0.5 : 1.60420 Request Task > 0.5 : 2.09777Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.24321 Timer Late > 0.5 : 2.04331 Timer Late > 0.5 : 1.68247 Request Task > 0.5 : 2.10994Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.23681 Timer Late > 0.5 : 2.12441 Timer Late > 0.5 : 2.02855 Timer Late > 0.5 : 0.62178 Request Task > 0.5 : 2.06020Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.21443 Timer Late > 0.5 : 1.70048 Timer Late > 0.5 : 1.49155 Request Task > 0.5 : 2.17592Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.28973 Timer Late > 0.5 : 2.02540 Timer Late > 0.5 : 1.95717 Request Task > 0.5 : 2.00991Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.16045 Timer Late > 0.5 : 2.01647 Timer Late > 0.5 : 1.76358 Request Task > 0.5 : 2.04082Notify: Plugins::DynamicPlayList::Plugin::commandCallback65 Response Time> 0.5 : 2.17983 Timer Late > 0.5 : 2.14218 Timer Late > 0.5 : 2.0034
[slim] Re: 6.5.0 Problem
This is showing the server being unresponsive for 2.8 seconds: Request Task > 0.5 : 2.87201 Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 2.87269 Execute: Slim::Control::Commands::irCommand Response Time > 0.5 : 2.87340 However it doesn't give any immediate clues of why. Could I suggest you leave it running like this for a while and then look back at the log for each instance of "Response Time > 0.5" and see what it says just before this - it may give us more details. If it does not then we need to run this at the same time as some more debugging. -- Triode Triode's Profile: http://forums.slimdevices.com/member.php?userid=17 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
Is this the right bit?: ERROR: Failed to detect server IP address. Invalid argument ERROR: objectForUrl: Null track request! Backtrace: frame 0: Slim::Schema::objectForUrl (E:\Data\SlimServer\server/Plugins/TrackStat/Storage.pm line 128) frame 1: Plugins::TrackStat::Storage::objectForUrl (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2963) frame 2: Plugins::TrackStat::Plugin::startTimingNewSong (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2677) frame 3: Plugins::TrackStat::Plugin::playCommand (E:\Data\SlimServer\server/Plugins/TrackStat/Plugin.pm line 2905) frame 4: Plugins::TrackStat::Plugin::commandCallback65 (/PerlApp/Slim/Control/Request.pm line 1640) frame 5: Slim::Control::Request::notify (/PerlApp/Slim/Control/Request.pm line 753) frame 6: Slim::Control::Request::checkNotifications (slimserver.pl line 464) frame 7: main::idle (slimserver.pl line 35) frame 8: PerlSvc::Startup (perlsvc.pl line 1482) frame 9: PerlSvc::_startup (slimserver.pl line 0) frame 10: (eval) (slimserver.pl line 0) Timer Task > 0.5 : 0.56283 Plugins::LazySearch2::onFindTimer Response Time> 0.5 : 0.56302 Request Task > 0.5 : 0.54156Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 0.54225Execute: Slim::Control::Commands::irCommand Response Time> 0.5 : 0.54296 Request Task > 0.5 : 2.87201Execute: Slim::Control::Commands::buttonCommand Request Task > 0.5 : 2.87269Execute: Slim::Control::Commands::irCommand Response Time> 0.5 : 2.87340 Timer Late > 0.5 : 2.62038 Timer Late > 0.5 : 2.19853 Timer Late > 0.5 : 1.21976 -- MillmoorRon MillmoorRon's Profile: http://forums.slimdevices.com/member.php?userid=6413 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss
[slim] Re: 6.5.0 Problem
Could you run the diagnostics described in the following thread and post the output of the log here? http://forums.slimdevices.com/showthread.php?t=28118 -- Triode Triode's Profile: http://forums.slimdevices.com/member.php?userid=17 View this thread: http://forums.slimdevices.com/showthread.php?t=28195 ___ discuss mailing list discuss@lists.slimdevices.com http://lists.slimdevices.com/lists/listinfo/discuss