Triode wrote: > Can you confirm what is running at 100% and what the spotifyd log says? > I would not expect the act of doing a search to have changed cpu load > with the new code as its only rate limiting the point when the search > request is accepted (I hope). > > Edit - actually you are on a relatively low powered box, I've never > really looked at cpu load for that but suspect it could be high for > complex tasks.
Yes the TS-119 is not that powerful, but normally everything works fine. I tried some further investigating: 1) I restarted the LMS (to start clean) 2) Opend the plugin configuration page - stating "Helper App: Not Running" - but... 3) Pressed the self test button 4) Result: "All test complete - plugin is working correctly" 5) The spotifyd having 2.7% CPU Usage 6) Selected "Top 10 Tracks" from artist "Stan Getz" 7) Only minor increase of the spotifyd CPU Usage 8) Now after some time the message "Timed out wating for data" 9) At this point the spotifyd CPU usage increased to 79.1% (for - not sure - maybe 20 to 40 seconds) 10) Returning to the plugin configuration page - again stating "Helper App: Not Running" 11) After CPU load decreased I started the selftest and again everything was OK Logfile: [21:32:31.498772] main:416 /opt/ssods4/var/home/SqueezeboxServer/Cache/InstalledPlugins/Plugins/Spotify/Bin/arm-linux/spotifyd 2.2.9 started [21:32:31.499966] main:471 error on bind listenfd: Address already in use [21:32:35.718967] main:416 /opt/ssods4/var/home/SqueezeboxServer/Cache/InstalledPlugins/Plugins/Spotify/Bin/arm-linux/spotifydnoflac 2.2.9 started [21:32:35.720117] main:471 error on bind listenfd: Address already in use [21:34:01.881719] main:539 req: status.json res: (null) par: (null) prot: HTTP/1.1 auth: (null) [21:34:01.881909] process_status:1199 process status [21:34:01.900637] main:539 req: toplist.json res: (null) par: q=tracks&r=user prot: HTTP/1.1 auth: (null) [21:34:01.900860] main:1130 toplist type: 2 region: 17483 [21:34:02.341321] toplist_callback:728 toplist: ok [21:34:02.514684] metadata_updated:85 new meta [21:34:02.536465] main:539 req: status.json res: (null) par: (null) prot: HTTP/1.1 auth: (null) [21:34:02.536600] process_status:1199 process status [21:34:02.551954] main:539 req: streamtest.json res: (null) par: (null) prot: HTTP/1.1 auth: (null) [21:34:02.554412] main:879 created teststream radio search to find track [21:34:02.768515] teststream_search_callback:872 found track [21:34:02.769735] metadata_updated:85 new meta [21:34:03.772660] main:1575 Now playing: Outside - Original LP Version [21:34:05.993861] log_message:80 log: 19:34:05.993 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:34:07.268438] log_message:80 log: 19:34:07.268 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:34:19.681548] log_message:80 log: 19:34:19.681 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:34:32.543772] log_message:80 log: 19:34:32.543 I [offline-mgr:2116] 0 files unlocked. 0 images unlocked [21:34:36.587919] log_message:80 log: 19:34:36.587 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:34:55.055501] log_message:80 log: 19:34:55.055 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:35:10.577555] log_message:80 log: 19:35:10.577 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:35:27.237979] log_message:80 log: 19:35:27.237 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:35:44.269439] log_message:80 log: 19:35:44.269 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:36:01.642092] log_message:80 log: 19:36:01.642 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:36:17.250294] log_message:80 log: 19:36:17.250 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:36:33.100747] log_message:80 log: 19:36:33.100 I [file_streamer_simple.cpp:789] Request for file f37ffd2ad74c14f25318db8a2ebb94f8be4b61ba complete (code: 0) [21:37:17.077378] end_of_track:624 end of track [21:37:17.098436] main:539 req: streamtest.json res: spotify:track:5D6rk7Zr7FRy5m2fDte5fp par: (null) prot: HTTP/1.1 auth: (null) [21:37:17.098841] main:1575 Now playing: Outside - Original LP Version [21:37:17.813640] main:539 req: browse.json res: spotify:track:5D6rk7Zr7FRy5m2fDte5fp par: (null) prot: HTTP/1.0 auth: (null) [21:37:17.813877] main:996 queuing track browse [21:37:17.813977] process_track:1154 process track [21:37:17.814124] process_track:1178 browse track: ok [21:37:17.819653] main:539 req: browse.json res: spotify:track:5D6rk7Zr7FRy5m2fDte5fp par: (null) prot: HTTP/1.0 auth: (null) [21:37:17.819875] main:996 queuing track browse [21:37:17.819975] process_track:1154 process track [21:37:17.820118] process_track:1178 browse track: ok [21:37:17.821268] main:539 req: stream.flc res: spotify:track:5D6rk7Zr7FRy5m2fDte5fp par: player=00%3A04%3A20%3A06%3Aab%3A84&start=0&sync=1&id=1 prot: SPOTSTREAM/1.0 auth: (null) [21:37:17.821444] main:727 newstream connection from 10.0.1.2 [21:37:17.821562] main:1575 Now playing: Outside - Original LP Version [21:37:37.310890] main:539 req: cover.jpg res: spotify:image:3a838e45442b797535ddbd9e49015190718e0fbf par: (null) prot: HTTP/1.0 auth: (null) [21:37:37.401250] main:539 req: cover.jpg res: spotify:image:3a838e45442b797535ddbd9e49015190718e0fbf par: (null) prot: HTTP/1.0 auth: (null) [21:37:37.447247] write_cover:96 cover fetch: No error [21:37:37.447646] write_cover:96 cover fetch: No error [21:37:50.339070] main:539 req: prefetch.json res: spotify:track:5D6rk7Zr7FRy5m2fDte5fp par: (null) prot: HTTP/1.0 auth: (null) [21:37:50.339463] main:903 prefetching track: No error [21:41:33.021175] end_of_track:624 end of track [21:42:31.789128] main:539 req: search.json res: (null) par: o=0&arq=10&alq=10&trq=10&q=stan%20getz prot: HTTP/1.0 auth: (null) [21:42:31.789347] main:1063 search: stan getz offset: 0 artist count: 10 album count: 10 track count: 10 [21:42:31.938252] search_callback:575 search: ok [21:42:32.004826] metadata_updated:85 new meta [21:42:39.440577] main:539 req: search.json res: (null) par: o=0&arq=100&q=stan%20getz prot: HTTP/1.0 auth: (null) [21:42:39.440763] main:1063 search: stan getz offset: 0 artist count: 100 album count: 0 track count: 0 [21:42:39.853277] search_callback:575 search: ok [21:42:41.063181] main:539 req: search.json res: (null) par: o=90&arq=18&q=stan%20getz prot: HTTP/1.0 auth: (null) [21:42:41.063364] main:1063 search: stan getz offset: 90 artist count: 18 album count: 0 track count: 0 [21:42:41.242899] search_callback:575 search: ok [21:42:42.127447] main:539 req: browse.json res: spotify:artist:0FMucZsEnCxs5pqBjHjIc8 par: (null) prot: HTTP/1.0 auth: (null) [21:42:43.311534] main:944 created artist browse [21:42:43.469383] browse_artist_callback:147 browse artist: ok [21:42:51.877932] main:539 req: tracks.json res: spotify:artist:0FMucZsEnCxs5pqBjHjIc8 par: (null) prot: HTTP/1.0 auth: (null) [21:42:51.878250] main:1225 created artist tracks browse [21:43:02.015035] log_message:80 log: 19:43:02.014 I [offline-mgr:2116] 0 files unlocked. 0 images unlocked [21:44:01.268556] _sockprintf:391 unable to write to socket: 9 errno: 32 Broken pipe [21:44:01.268791] browse_artist_tracks_callback:310 browse artist tracks: ok [21:45:18.988645] metadata_updated:85 new meta [21:45:19.152473] metadata_updated:85 new meta [21:45:20.520259] metadata_updated:85 new meta [21:45:50.372760] log_message:80 log: 19:45:50.372 I [offline-mgr:2116] 0 files unlocked. 0 images unlocked [21:46:05.760106] main:539 req: tracks.json res: spotify:artist:0FMucZsEnCxs5pqBjHjIc8 par: (null) prot: HTTP/1.0 auth: (null) [21:46:05.760459] main:1225 created artist tracks browse [21:47:01.681912] browse_artist_tracks_callback:310 browse artist tracks: ok [21:48:20.221381] metadata_updated:85 new meta [21:48:20.382268] metadata_updated:85 new meta [21:48:20.382730] main:539 req: tracks.json res: spotify:artist:0FMucZsEnCxs5pqBjHjIc8 par: (null) prot: HTTP/1.0 auth: (null) [21:48:20.382973] main:1225 created artist tracks browse [21:48:20.383225] browse_artist_tracks_callback:310 browse artist tracks: ok Jens ------------------------------------------------------------------------ Jens_dk's Profile: http://forums.slimdevices.com/member.php?userid=12842 View this thread: http://forums.slimdevices.com/showthread.php?t=79706 _______________________________________________ plugins mailing list [email protected] http://lists.slimdevices.com/mailman/listinfo/plugins
