These are lines from a local dev log. Notice the two identical (except for time taken) entries for the requests. Every request has two entries with the 2nd entry taking longer. This is a dev environment so the .css is referenced. On the server this same app is doing the same thing. Any ideas? Is it bug in my code? I, [2012-01-14T10:54:09.089535 #34573] INFO -- : listening on addr=0.0.0.0:8080 fd=3 I, [2012-01-14T10:54:09.090163 #34573] INFO -- : worker=0 spawning... I, [2012-01-14T10:54:09.092941 #34573] INFO -- : master process ready I, [2012-01-14T10:54:09.095054 #34597] INFO -- : worker=0 spawned pid=34597 I, [2012-01-14T10:54:09.095875 #34597] INFO -- : Refreshing Gem list I, [2012-01-14T10:54:19.744259 #34597] INFO -- : worker=0 ready 127.0.0.1 - - [14/Jan/2012 10:54:26] "GET /page/url HTTP/1.1" 200 29950 0.3179 127.0.0.1 - - [14/Jan/2012 10:54:26] "GET /page/url HTTP/1.1" 200 29950 0.5209 127.0.0.1 - - [14/Jan/2012 10:54:26] "GET /ui/base.css HTTP/1.1" 200 33178 0.0670 127.0.0.1 - - [14/Jan/2012 10:54:26] "GET /ui/base.css HTTP/1.1" 200 33178 0.0716
Rob Sutherland <rob at roberocity.com> wrote:> These are lines from a local dev log. Notice the two identical (except > for time taken) entries for the requests. Every request has two > entries with the 2nd entry taking longer. This is a dev environment so > the .css is referenced. On the server this same app is doing the same > thing. > > Any ideas? Is it bug in my code?It looks like you''re redundantly loading Rack::CommonLogger Rack adds Rack::CommonLogger to the middleware stack in both "development" and "deployment" envirnoments. The longer time is probably from Rack::Lint, which also gets added to the "development" environment. You can also specify "RACK_ENV=none" to have Rack not load any middleware by default, giving you (or at least your higher-level framework) full control over what middleware you load.
Eric Wong. That was exactly the problem. The app is a Sinatra app and Sinatra and rack were both writing to the log. Rob -- Rob Sutherland On Saturday, January 14, 2012 at 3:03 PM, Eric Wong wrote:> Rob Sutherland <rob at roberocity.com (mailto:rob at roberocity.com)> wrote: > > These are lines from a local dev log. Notice the two identical (except > > for time taken) entries for the requests. Every request has two > > entries with the 2nd entry taking longer. This is a dev environment so > > the .css is referenced. On the server this same app is doing the same > > thing. > > > > Any ideas? Is it bug in my code? > > It looks like you''re redundantly loading Rack::CommonLogger > > Rack adds Rack::CommonLogger to the middleware stack in both > "development" and "deployment" envirnoments. The longer time is > probably from Rack::Lint, which also gets added to the "development" > environment. > > You can also specify "RACK_ENV=none" to have Rack not load any > middleware by default, giving you (or at least your higher-level > framework) full control over what middleware you load. > _______________________________________________ > Unicorn mailing list - mongrel-unicorn at rubyforge.org (mailto:mongrel-unicorn at rubyforge.org) > http://rubyforge.org/mailman/listinfo/mongrel-unicorn > Do not quote signatures (like this one) or top post when replying