I was having a problem with SqueezeCenter DNS resolution.  I raised bug 5918.  
This seems to have been resolved (it seems to resolve the DNS names okay now), 
but I still get HTTP timeouts.

This appears to be due to something doing too much work at startup.  I can see 
messages about purging FileCache/CustomBrowse stuff, which could be 
SqueezeCenter generic processing, followed by a report that CustomBrowse 
lateInitPlugin took over 0.5 seconds.

The following line in the log seems to be the key issue:

[12:16:37.5554] Scheduler Task   >   0.5 : 14.74586  
Plugins::CustomBrowse::Plugin::lateInitPlugin

Here's a section of the startup logging:

[12:16:25.2345] Response Time    >   0.5 :  0.53047  
[12:16:25.2399] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/CustomScan/2.0.beta02/MenuTemplates - skipping, purged 
recently
[12:16:25.6476] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/TrackStat/2.0.beta01/MenuTemplates - skipping, purged 
recently
[12:16:26.1096] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/CustomScan/2.0.beta02/Mixes - skipping, purged recently
[12:16:26.3827] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/CustomSkip/2.0.beta01/Mixes - skipping, purged recently
[12:16:26.4917] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/TrackStat/2.0.beta01/Mixes - skipping, purged recently
[12:16:26.6740] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/DynamicPlayList/2.0.beta03/Mixes - skipping, purged 
recently
[12:16:26.8340] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/CustomScan/2.0.beta02/Menus - skipping, purged recently
[12:16:26.9564] Slim::Utils::Cache::cleanup (206) Cache purge: 
FileCache/CustomBrowse/TrackStat/2.0.beta01/Menus - skipping, purged recently
[12:16:27.3449] Slim::Networking::Async::HTTP::_http_error (262) Error: [Error 
on HTTP socket: Bad file descriptor]
[12:16:27.3454] Slim::Networking::SimpleAsyncHTTP::onError (183) Failed to 
connect to http://www.squeezenetwork.com/api/v1/time (Error on HTTP socket: Bad 
file descriptor)
[12:16:27.3458] Slim::Web::Settings::Server::Wizard::__ANON__ (44) Couldn't 
connect to squeezenetwork.com - do we need a proxy?
Error on HTTP socket: Bad file descriptor
[12:16:27.9233] Slim::Networking::Async::_async_error (248) Error: [Timed out 
waiting for data]
[12:16:27.9237] Slim::Networking::Async::HTTP::_http_error (262) Error: [Timed 
out waiting for data]
[12:16:27.9241] Slim::Networking::SimpleAsyncHTTP::onError (183) Failed to 
connect to http://update.slimdevices.com/update/firmware/7.0/jive.version.sha 
(Timed out waiting for data)
[12:16:27.9276] Slim::Utils::Firmware::downloadAsyncError (480) Warning: 
Firmware: Failed to download 
http://update.slimdevices.com/update/firmware/7.0/jive.version.sha (Timed out 
waiting for data), will try again in 10 minutes.
[12:16:37.5554] Scheduler Task   >   0.5 : 14.74586  
Plugins::CustomBrowse::Plugin::lateInitPlugin
[12:16:37.5625] Slim::Networking::Async::HTTP::_http_read_body (398) Read body: 
[30] bytes
[12:16:37.5805] Slim::Networking::Async::HTTP::_http_read_body (450) Body read
[12:16:37.5809] Slim::Networking::SimpleAsyncHTTP::onBody (203) status for 
http://www.squeezenetwork.com:3000 is 200 OK
[12:16:37.5814] Slim::Networking::SimpleAsyncHTTP::onBody (236) Decompressing 
gzip'ed content
[12:16:37.5819] Slim::Networking::SimpleAsyncHTTP::onBody (325) Done
[12:16:37.5826] Slim::Networking::SimpleAsyncHTTP::_createHTTPRequest (105) 
GETing http://www.squeezenetwork.com:3000/api/v1/prefs/can_sync
[12:16:37.5843] Slim::Networking::Async::DNS::resolve (147) Starting async DNS 
lookup for [www.squeezenetwork.com] using server(s) [158.152.1.58, 
158.152.1.43, 4.2.2.2]
[12:16:37.5941] Slim::Networking::SimpleAsyncHTTP::_createHTTPRequest (105) 
GETing http://www.squeezenetwork.com:3000/api/v1/players
[12:16:37.5953] Slim::Networking::Async::DNS::resolve (147) Starting async DNS 
lookup for [www.squeezenetwork.com] using server(s) [158.152.1.58, 
158.152.1.43, 4.2.2.2]
[12:16:37.6738] Slim::Networking::Async::DNS::_dns_read (260) Resolved 
www.squeezenetwork.com to 207.7.156.10 (ttl 7), request took 77 ms
[12:16:37.6753] Slim::Networking::Async::connect (117) Connecting to 
www.squeezenetwork.com:3000
[12:16:37.8832] Slim::Networking::Async::DNS::_dns_read (260) Resolved 
www.squeezenetwork.com to 207.7.156.10 (ttl 7), request took 298 ms
[12:16:37.8846] Slim::Networking::Async::connect (117) Connecting to 
www.squeezenetwork.com:3000
[12:16:38.0840] Slim::Networking::Async::_async_connect (181) connected, ready 
to write request
[12:16:38.0850] Slim::Networking::Async::write_async (211) Sending: [GET 
/api/v1/players HTTP/1.1
_______________________________________________
plugins mailing list
[email protected]
http://lists.slimdevices.com/lists/listinfo/plugins

Reply via email to