test/dummy/log/test.log in streamit-0.0.7 vs test/dummy/log/test.log in streamit-0.0.8

- old
+ new

@@ -18900,5 +18900,1172 @@ AREL (0.0ms) DELETE FROM "users" WHERE 1=1 AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 AREL (0.0ms) DELETE FROM "items" WHERE 1=1 AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:33:54.749927', '2011-05-30 19:33:54.749927') AREL (0.1ms) INSERT INTO "items" ("title", "created_at", "updated_at") VALUES (NULL, '2011-05-30 19:33:54.750955', '2011-05-30 19:33:54.750955') + AREL (0.4ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:40:33 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (0.0ms) +Rendered users/new.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.7ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:40:33 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + SQL (0.0ms)  SELECT name + FROM sqlite_master + WHERE type = 'table' AND NOT name = 'sqlite_sequence' + + AREL (0.0ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:40:33.283992', '2011-05-30 19:40:33.283992') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 0ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:40:33 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.0ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (2.1ms) +Rendered users/new.html.erb within layouts/application (3.6ms) +Completed 200 OK in 4ms (Views: 4.1ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.542270', '2011-05-30 19:41:04.542270') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 50ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.7ms) +Completed 200 OK in 3ms (Views: 2.3ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.9ms) +Rendered users/new.html.erb within layouts/application (3.3ms) +Completed 200 OK in 4ms (Views: 3.7ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.610211', '2011-05-30 19:41:04.610211') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 4ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.1ms) +Completed 200 OK in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.629570', '2011-05-30 19:41:04.629570') +Redirected to http://www.example.com/users/4 +Completed 302 Found in 3ms + + +Started GET "/users/4" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"4"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 4) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?ago=30&limit=2" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"ago"=>"30", "limit"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (18.9ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.8ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (22.9ms) +Completed 200 OK in 26ms (Views: 24.8ms | ActiveRecord: 0.3ms) + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (2.0ms) +Rendered users/new.html.erb within layouts/application (3.8ms) +Completed 200 OK in 5ms (Views: 4.3ms | ActiveRecord: 0.6ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.687076', '2011-05-30 19:41:04.687076') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 4ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.1ms | ActiveRecord: 0.4ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.2ms) +Completed 200 OK in 4ms (Views: 3.5ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.707988', '2011-05-30 19:41:04.707988') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 3ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.2ms) +Completed 200 OK in 4ms (Views: 3.5ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.727808', '2011-05-30 19:41:04.727808') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 3ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.1ms) +Completed 200 OK in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.747032', '2011-05-30 19:41:04.747032') +Redirected to http://www.example.com/users/4 +Completed 302 Found in 3ms + + +Started GET "/users/4" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"4"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 4) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?limit=2" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"limit"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (1.3ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.7ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (3.9ms) +Completed 200 OK in 5ms (Views: 4.1ms | ActiveRecord: 0.3ms) + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:40:33 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (0.0ms) +Rendered users/new.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.6ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:40:33 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.0ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:40:33.822733', '2011-05-30 19:40:33.822733') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 0ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:40:33 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.0ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.0ms) +Completed 200 OK in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.855342', '2011-05-30 19:41:04.855342') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 3ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 1.9ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.0ms) +Completed 200 OK in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:41:04.874303', '2011-05-30 19:41:04.874303') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 3ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?ago=30" for 127.0.0.1 at 2011-05-30 12:41:04 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"ago"=>"30"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (1.3ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.7ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (3.7ms) +Completed 200 OK in 5ms (Views: 3.9ms | ActiveRecord: 0.3ms) + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:41:04.898643', '2011-05-30 19:41:04.898643') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:41:04.905198', '2011-05-30 19:41:04.905198') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:41:04.909274', '2011-05-30 19:41:04.909274') + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:04.930506', '2011-05-30 19:41:04.930506') + TestUser Load (0.5ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:04.940596', '2011-05-30 19:41:04.940596') + AREL (0.1ms) UPDATE "users" SET "name" = 'davis', "updated_at" = '2011-05-30 19:41:04.942857' WHERE ("users"."id" = 1) + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:04.950488', '2011-05-30 19:41:04.950488') + AREL (0.1ms) UPDATE "users" SET "name" = 'davis', "updated_at" = '2011-05-30 19:41:04.952729' WHERE ("users"."id" = 1) + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:04.960197', '2011-05-30 19:41:04.960197') + AREL (0.1ms) UPDATE "users" SET "image_url" = 'image', "updated_at" = '2011-05-30 19:41:04.962868' WHERE ("users"."id" = 1) + TestUser Load (0.4ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:04.971469', '2011-05-30 19:41:04.971469') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:04.976748', '2011-05-30 19:41:04.976748') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:04.983028', '2011-05-30 19:41:04.983028') + AREL (0.1ms) INSERT INTO "items" ("title", "created_at", "updated_at") VALUES (NULL, '2011-05-30 19:41:04.987507', '2011-05-30 19:41:04.987507') + AREL (0.2ms) INSERT INTO "watchings" ("user_id", "item_id", "created_at", "updated_at") VALUES (1, 1, '2011-05-30 19:41:04.997673', '2011-05-30 19:41:04.997673') + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + Item Load (0.1ms) SELECT "items".* FROM "items" WHERE ("items"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:41:05.061129', '2011-05-30 19:41:05.061129') + AREL (0.1ms) INSERT INTO "items" ("title", "created_at", "updated_at") VALUES (NULL, '2011-05-30 19:41:05.062140', '2011-05-30 19:41:05.062140') + AREL (0.6ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:03 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (0.0ms) +Rendered users/new.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.9ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:03 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + SQL (0.0ms)  SELECT name + FROM sqlite_master + WHERE type = 'table' AND NOT name = 'sqlite_sequence' + + AREL (0.0ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:03.177618', '2011-05-30 19:42:03.177618') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 0ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:42:03 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.0ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (2.0ms) +Rendered users/new.html.erb within layouts/application (3.6ms) +Completed 200 OK in 4ms (Views: 4.2ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.436873', '2011-05-30 19:42:34.436873') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 50ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.7ms) +Completed 200 OK in 3ms (Views: 2.2ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.2ms) +Completed 200 OK in 4ms (Views: 3.6ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.503974', '2011-05-30 19:42:34.503974') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 4ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.1ms) +Completed 200 OK in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.523908', '2011-05-30 19:42:34.523908') +Redirected to http://www.example.com/users/4 +Completed 302 Found in 3ms + + +Started GET "/users/4" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"4"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 4) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?ago=30&limit=2" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"ago"=>"30", "limit"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (19.3ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.8ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (23.4ms) +Completed 200 OK in 27ms (Views: 25.3ms | ActiveRecord: 0.3ms) + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (2.1ms) +Rendered users/new.html.erb within layouts/application (3.9ms) +Completed 200 OK in 5ms (Views: 4.4ms | ActiveRecord: 0.6ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.581106', '2011-05-30 19:42:34.581106') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 4ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.1ms | ActiveRecord: 0.4ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.2ms) +Completed 200 OK in 4ms (Views: 3.6ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.601939', '2011-05-30 19:42:34.601939') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 4ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.3ms) +Completed 200 OK in 4ms (Views: 3.8ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.622099', '2011-05-30 19:42:34.622099') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 3ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.2ms) +Completed 200 OK in 4ms (Views: 3.6ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.641869', '2011-05-30 19:42:34.641869') +Redirected to http://www.example.com/users/4 +Completed 302 Found in 3ms + + +Started GET "/users/4" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"4"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 4) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 1.9ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?limit=2" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"limit"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (1.3ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.7ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (3.9ms) +Completed 200 OK in 5ms (Views: 4.0ms | ActiveRecord: 0.2ms) + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:03 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (0.0ms) +Rendered users/new.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.6ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:03 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.0ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:03.717844', '2011-05-30 19:42:03.717844') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 0ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:42:03 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.0ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.1ms) +Completed 200 OK in 4ms (Views: 3.5ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.750339', '2011-05-30 19:42:34.750339') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 3ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.6ms) +Rendered users/new.html.erb within layouts/application (3.0ms) +Completed 200 OK in 3ms (Views: 3.3ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:42:34.769587', '2011-05-30 19:42:34.769587') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 3ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?ago=30" for 127.0.0.1 at 2011-05-30 12:42:34 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"ago"=>"30"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (1.3ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.7ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (3.8ms) +Completed 200 OK in 5ms (Views: 4.0ms | ActiveRecord: 0.3ms) + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:42:34.794532', '2011-05-30 19:42:34.794532') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:42:34.801275', '2011-05-30 19:42:34.801275') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:42:34.805465', '2011-05-30 19:42:34.805465') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.827377', '2011-05-30 19:42:34.827377') + TestUser Load (0.6ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.837264', '2011-05-30 19:42:34.837264') + AREL (0.1ms) UPDATE "users" SET "name" = 'davis', "updated_at" = '2011-05-30 19:42:34.840288' WHERE ("users"."id" = 1) + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.847878', '2011-05-30 19:42:34.847878') + AREL (0.1ms) UPDATE "users" SET "name" = 'davis', "updated_at" = '2011-05-30 19:42:34.849979' WHERE ("users"."id" = 1) + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.857734', '2011-05-30 19:42:34.857734') + AREL (0.1ms) UPDATE "users" SET "image_url" = 'image', "updated_at" = '2011-05-30 19:42:34.860076' WHERE ("users"."id" = 1) + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.868222', '2011-05-30 19:42:34.868222') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.874240', '2011-05-30 19:42:34.874240') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.881095', '2011-05-30 19:42:34.881095') + AREL (0.2ms) INSERT INTO "items" ("title", "created_at", "updated_at") VALUES (NULL, '2011-05-30 19:42:34.885688', '2011-05-30 19:42:34.885688') + AREL (0.2ms) INSERT INTO "watchings" ("user_id", "item_id", "created_at", "updated_at") VALUES (1, 1, '2011-05-30 19:42:34.896621', '2011-05-30 19:42:34.896621') + TestUser Load (0.4ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + Item Load (0.1ms) SELECT "items".* FROM "items" WHERE ("items"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:42:34.960884', '2011-05-30 19:42:34.960884') + AREL (0.1ms) INSERT INTO "items" ("title", "created_at", "updated_at") VALUES (NULL, '2011-05-30 19:42:34.961876', '2011-05-30 19:42:34.961876') + AREL (0.4ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:09 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (0.0ms) +Rendered users/new.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.7ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:09 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + SQL (0.0ms)  SELECT name + FROM sqlite_master + WHERE type = 'table' AND NOT name = 'sqlite_sequence' + + AREL (0.0ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:09.140543', '2011-05-30 19:43:09.140543') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 0ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:43:09 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.0ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (2.0ms) +Rendered users/new.html.erb within layouts/application (3.5ms) +Completed 200 OK in 4ms (Views: 4.0ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.449271', '2011-05-30 19:43:40.449271') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 4ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.1ms) +Completed 200 OK in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.469255', '2011-05-30 19:43:40.469255') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 3ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.2ms) +Completed 200 OK in 4ms (Views: 3.5ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.488669', '2011-05-30 19:43:40.488669') +Redirected to http://www.example.com/users/4 +Completed 302 Found in 3ms + + +Started GET "/users/4" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"4"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 4) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 1.9ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?ago=30&limit=2" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"ago"=>"30", "limit"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (19.8ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.8ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (23.9ms) +Completed 200 OK in 27ms (Views: 26.0ms | ActiveRecord: 0.3ms) + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (2.0ms) +Rendered users/new.html.erb within layouts/application (3.6ms) +Completed 200 OK in 4ms (Views: 4.2ms | ActiveRecord: 0.6ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.546448', '2011-05-30 19:43:40.546448') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 4ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.4ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.7ms) +Rendered users/new.html.erb within layouts/application (3.1ms) +Completed 200 OK in 4ms (Views: 3.5ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.568314', '2011-05-30 19:43:40.568314') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 3ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.7ms) +Completed 200 OK in 3ms (Views: 2.2ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (53.7ms) +Rendered users/new.html.erb within layouts/application (55.4ms) +Completed 200 OK in 56ms (Views: 55.8ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.641750', '2011-05-30 19:43:40.641750') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 4ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.6ms) +Completed 200 OK in 3ms (Views: 2.2ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.9ms) +Rendered users/new.html.erb within layouts/application (3.3ms) +Completed 200 OK in 4ms (Views: 3.7ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.662568', '2011-05-30 19:43:40.662568') +Redirected to http://www.example.com/users/4 +Completed 302 Found in 4ms + + +Started GET "/users/4" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"4"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 4) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 1.9ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?limit=2" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"limit"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (1.4ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.6ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (3.9ms) +Completed 200 OK in 5ms (Views: 4.1ms | ActiveRecord: 0.3ms) + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:09 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (0.0ms) +Rendered users/new.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.6ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:09 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.0ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:09.684716', '2011-05-30 19:43:09.684716') +Redirected to http://www.example.com/users/1 +Completed 302 Found in 0ms + + +Started GET "/users/1" for 127.0.0.1 at 2011-05-30 12:43:09 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"1"} + User Load (0.0ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 +Rendered users/show.html.erb within layouts/application (0.0ms) +Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.2ms) +Completed 200 OK in 4ms (Views: 3.5ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.718324', '2011-05-30 19:43:40.718324') +Redirected to http://www.example.com/users/2 +Completed 302 Found in 3ms + + +Started GET "/users/2" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"2"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered users/show.html.erb within layouts/application (2.0ms) +Completed 200 OK in 3ms (Views: 2.5ms | ActiveRecord: 0.3ms) + + +Started GET "/users/new" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#new as HTML +Rendered users/_form.html.erb (1.8ms) +Rendered users/new.html.erb within layouts/application (3.3ms) +Completed 200 OK in 4ms (Views: 3.7ms | ActiveRecord: 0.0ms) + + +Started POST "/users" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#create as HTML + Parameters: {"utf8"=>"✓", "user"=>{"name"=>"davis", "image_url"=>""}, "commit"=>"Create User"} + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', '', '2011-05-30 19:43:40.739233', '2011-05-30 19:43:40.739233') +Redirected to http://www.example.com/users/3 +Completed 302 Found in 3ms + + +Started GET "/users/3" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by UsersController#show as HTML + Parameters: {"id"=>"3"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered users/show.html.erb within layouts/application (1.5ms) +Completed 200 OK in 3ms (Views: 1.9ms | ActiveRecord: 0.3ms) + + +Started GET "/streamit/fetch?ago=30" for 127.0.0.1 at 2011-05-30 12:43:40 -0700 + Processing by Streamit::StreamsController#fetch as HTML + Parameters: {"ago"=>"30"} + User Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 2) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (1.4ms) + User Load (0.1ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 3) LIMIT 1 +Rendered streamit/streams/_stream.html.erb (0.7ms) +Rendered /Users/linhe/Projects/streamit/app/views/streamit/streams/fetch.html.erb (3.8ms) +Completed 200 OK in 5ms (Views: 3.9ms | ActiveRecord: 0.3ms) + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:43:40.763883', '2011-05-30 19:43:40.763883') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:43:40.770475', '2011-05-30 19:43:40.770475') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES ('davis', NULL, '2011-05-30 19:43:40.774684', '2011-05-30 19:43:40.774684') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.796513', '2011-05-30 19:43:40.796513') + TestUser Load (0.6ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.3ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.805825', '2011-05-30 19:43:40.805825') + AREL (0.1ms) UPDATE "users" SET "name" = 'davis', "updated_at" = '2011-05-30 19:43:40.807952' WHERE ("users"."id" = 1) + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.816037', '2011-05-30 19:43:40.816037') + AREL (0.1ms) UPDATE "users" SET "name" = 'davis', "updated_at" = '2011-05-30 19:43:40.818232' WHERE ("users"."id" = 1) + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.825738', '2011-05-30 19:43:40.825738') + AREL (0.1ms) UPDATE "users" SET "image_url" = 'image', "updated_at" = '2011-05-30 19:43:40.828471' WHERE ("users"."id" = 1) + TestUser Load (0.2ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.836391', '2011-05-30 19:43:40.836391') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.1ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.2ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.842331', '2011-05-30 19:43:40.842331') + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.1ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.905554', '2011-05-30 19:43:40.905554') + AREL (0.1ms) INSERT INTO "items" ("title", "created_at", "updated_at") VALUES (NULL, '2011-05-30 19:43:40.909792', '2011-05-30 19:43:40.909792') + AREL (0.2ms) INSERT INTO "watchings" ("user_id", "item_id", "created_at", "updated_at") VALUES (1, 1, '2011-05-30 19:43:40.919743', '2011-05-30 19:43:40.919743') + TestUser Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" = 1) LIMIT 1 + Item Load (0.1ms) SELECT "items".* FROM "items" WHERE ("items"."id" = 1) LIMIT 1 + AREL (0.2ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "users" WHERE 1=1 + AREL (0.0ms) DELETE FROM "watchings" WHERE 1=1 + AREL (0.0ms) DELETE FROM "items" WHERE 1=1 + AREL (0.1ms) INSERT INTO "users" ("name", "image_url", "created_at", "updated_at") VALUES (NULL, NULL, '2011-05-30 19:43:40.930293', '2011-05-30 19:43:40.930293') + AREL (0.1ms) INSERT INTO "items" ("title", "created_at", "updated_at") VALUES (NULL, '2011-05-30 19:43:40.931256', '2011-05-30 19:43:40.931256')