# Logfile created on Sun Oct 25 02:06:21 +0900 2009 Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:06:21) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (1.9ms) Completed in 21ms (View: 15, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:06:21) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.9ms) Completed in 6ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:06:21) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_ja Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:22) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.2ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (1.2ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Parameters: {"lang"=>"ja"} Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:06:43) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.8ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Parameters: {"lang"=>"ja"} Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:07:40) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Cached fragment hit: views/test.host/articles/list_en (0.5ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.8ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Parameters: {"lang"=>"ja"} Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_ja Completed in 4ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:08:35) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.8ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Parameters: {"lang"=>"ja"} Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:13:55) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Cached fragment hit: en (0.2ms) Rendering articles/list Couldn't create cache directory: en (private method `gsub' called for [#]:Array) Cached fragment miss: en (0.3ms) Completed in 9ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:15) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Cached fragment hit: en (0.2ms) Rendering articles/list Couldn't create cache directory: en (private method `gsub' called for [#]:Array) Cached fragment miss: en (0.3ms) Completed in 9ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:21:55) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:23:18) [GET] Cached fragment hit: en (0.2ms) Rendering articles/list Couldn't create cache directory: en (private method `gsub' called for [#]:Array) Cached fragment miss: en (0.3ms) Completed in 9ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:18) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:18) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:18) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:18) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:19) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Cached fragment hit: views/test.host/articles/list (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list (2.1ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:23:49) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :store_options=>{}, :layout=>nil}>] rendered_or_redirected. Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#nil, :store_options=>{}, :layout=>nil}>, @identifier=nil>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :store_options=>{}, :layout=>nil}>] rendered_or_redirected. Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#nil, :store_options=>{}, :layout=>nil}>, @identifier=nil>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_de Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:24:25) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Cached fragment hit: views/test.host/articles/list_en (0.5ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.4ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = (0.0ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:25:17) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_de Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_de Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:26:34) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index Completed in 7ms (View: 4, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:28:52) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:03) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Rendering articles/index Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:29:57) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.0ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:23) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.0ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:31:29) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.0ms) Completed in 10ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Rendering articles/index_de Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:33:41) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.9ms) Completed in 11ms (View: 3, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:34:02) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.0ms) Completed in 10ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = (0.3ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_ja Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:00) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 10ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = (0.3ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:36) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index Completed in 2ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:35:37) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.2ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 02:38:09) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 16ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.8ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index Completed in 8ms (View: 7, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_ja Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_de Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Rendering articles/index Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:26:18) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.7ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.8ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_ja Completed in 6ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_de Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 5ms (View: 4, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:31:18) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.1ms) Completed in 12ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (1.7ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :layout=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :if=>nil, :only=>#}, @method=#nil, :layout=>nil, :store_options=>{}}>, @identifier=nil, @kind=:filter>] did_not_yield. Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_ja Completed in 8ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:02) [GET] Rendering articles/index_de Completed in 17ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Rendering articles/index_ja Completed in 6ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-10-25 03:33:03) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (17.8ms) Completed in 32ms (View: 7, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (1.3ms) Completed in 8ms (View: 3, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Filter chain halted as [#nil, :cache_path=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :unless=>nil, :only=>#}, @method=#nil, :cache_path=>nil, :store_options=>{}}>, @identifier=nil>] did_not_yield. Completed in 21ms (View: 16, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Filter chain halted as [#nil, :cache_path=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :unless=>nil, :only=>#}, @method=#nil, :cache_path=>nil, :store_options=>{}}>, @identifier=nil>] did_not_yield. Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.3ms) Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index Completed in 73ms (View: 15, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_fr_FR Completed in 16ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index Completed in 18ms (View: 14, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index Completed in 20ms (View: 16, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_fr_FR Completed in 12ms (View: 9, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_ja Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_ja Completed in 8ms (View: 4, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_fr_FR Completed in 20ms (View: 17, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_ja Completed in 16ms (View: 10, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_de Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_de Completed in 16ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_ja Completed in 21ms (View: 11, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_fr_FR Completed in 13ms (View: 10, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index_ja Completed in 11ms (View: 9, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index Completed in 12ms (View: 8, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Rendering articles/index Completed in 11ms (View: 9, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 13ms (View: 11, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 10:27:08) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.4ms) Completed in 11ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (9.3ms) Completed in 24ms (View: 12, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :cache_path=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :unless=>nil, :only=>#}, @method=#nil, :cache_path=>nil, :store_options=>{}}>, @identifier=nil>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.4ms) Filter chain halted as [#nil, :cache_path=>nil, :store_options=>{}}>] rendered_or_redirected. Filter chain halted as [#nil, :unless=>nil, :only=>#}, @method=#nil, :cache_path=>nil, :store_options=>{}}>, @identifier=nil>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 124ms (View: 122, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index Completed in 7ms (View: 5, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 15ms (View: 5, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_fr_FR Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index Completed in 6ms (View: 4, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_fr_FR Completed in 12ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_de Completed in 6ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_ja Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index_ja Completed in 12ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-03 11:27:35) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (8.5ms) Completed in 20ms (View: 5, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.8ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @identifier=nil, @method=#>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @identifier=nil, @method=#>] did_not_yield. Completed in 4ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_ja Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_fr_FR Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_de Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_de Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 19:43:07) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.9ms) Completed in 13ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @identifier=nil, @method=#>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @identifier=nil, @method=#>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_fr_FR Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_fr_FR Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_de Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_de Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:31) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Cached fragment hit: views/test.host/articles/list_en (0.4ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (2.6ms) Completed in 15ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 8ms (View: 7, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_de Completed in 8ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Rendering articles/index Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:00:48) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (1.8ms) Completed in 10ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (1.0ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 11ms (View: 10, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_fr_FR Completed in 8ms (View: 6, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_fr_FR Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_de Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_de Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_ja Completed in 13ms (View: 10, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Rendering articles/index Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-08 22:01:19) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Cached fragment hit: views/test.host/articles/list_en (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (49.5ms) Completed in 62ms (View: 7, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.9ms) Completed in 7ms (View: 3, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_fr_FR Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_fr_FR Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_ja Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_fr_FR Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_de Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_de Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_ja Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_fr_FR Completed in 8ms (View: 4, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index_ja Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 4ms (View: 3, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:33:16) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_en (20.2ms) Completed in 32ms (View: 4, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.3ms) Rendering articles/list Cached fragment miss: views/test.host/articles/list_ja (0.7ms) Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Cached fragment hit: views/test.host/articles/list_en (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 5ms (View: 2, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#list (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Parameters: {"lang"=>"ja"} Cached fragment hit: views/test.host/articles/list_ja (0.2ms) Filter chain halted as [#nil, :only=>#, :unless=>nil}, @method=#, @identifier=nil>] did_not_yield. Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#expire_cache (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Parameters: {"lang"=>"ja"} Expired fragment: views/test.host/articles/list, lang = enja (0.2ms) Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles/list] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_fr_FR Completed in 2ms (View: 1, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index Completed in 3ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index Completed in 30ms (View: 4, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_fr_FR Completed in 6ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_ja Completed in 17ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_ja Completed in 7ms (View: 4, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_fr_FR Completed in 27ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_ja Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_de Completed in 5ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_de Completed in 27ms (View: 3, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_ja Completed in 19ms (View: 4, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_fr_FR Completed in 16ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index_ja Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Rendering articles/index Completed in 26ms (View: 24, DB: 0) | 200 OK [http://test.host/articles] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 3ms (View: 1, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Parameters: {"lang"=>"ja"} Rendering articles/index_ja Completed in 16ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja] Processing ArticlesController#index (for 0.0.0.0 at 2009-11-14 11:34:14) [GET] Parameters: {"lang"=>"en"} Rendering articles/index Completed in 4ms (View: 2, DB: 0) | 200 OK [http://test.host/articles?lang=ja]