ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" SQL (131.5ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:17:15.764914"], ["updated_at", "2016-12-23 21:17:15.764914"]]  (110.8ms) commit transaction Started GET "/orders/new" for 127.0.0.1 at 2016-12-23 22:17:15 +0100 Processing by OrdersController#new as HTML Rendered app/formies/forms/builtins.html.erb (0.6ms) Rendered orders/_new.html.erb (2.7ms) Rendered orders/new.html.erb within layouts/application (6.4ms) Completed 200 OK in 127ms (Views: 126.3ms | ActiveRecord: 0.0ms) SQL (109.7ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:17:16.164550"], ["updated_at", "2016-12-23 21:17:16.164550"]]  (114.8ms) commit transaction Started GET "/orders" for 127.0.0.1 at 2016-12-23 22:17:16 +0100 Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.7ms) Rendered app/formies/templates/labelled.html.erb (0.3ms) Rendered app/formies/templates/l_text_field.html.erb (3.4ms) Rendered orders/index.html.erb within layouts/application (12.7ms) Completed 200 OK in 15ms (Views: 14.4ms | ActiveRecord: 0.0ms) SQL (115.6ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:17:16.415971"], ["updated_at", "2016-12-23 21:17:16.415971"]]  (116.1ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" ORDER BY "orders"."id" ASC LIMIT 1 Started GET "/orders/160" for 127.0.0.1 at 2016-12-23 22:17:16 +0100 Processing by OrdersController#show as HTML Parameters: {"id"=>"160"}  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "hugo"], ["created_at", "2016-12-23 21:17:16.539032"], ["updated_at", "2016-12-23 21:17:16.539032"]]  (118.7ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 160]] Rendered app/formies/forms/l_text_field.html.erb (10.0ms) Rendered app/formies/forms/l_text_field.html.erb (0.5ms) Rendered app/formies/templates/hello.slim (11.9ms) Rendered orders/_form.html.erb (26.3ms) Rendered orders/show.html.erb within layouts/application (27.5ms) Completed 200 OK in 157ms (Views: 29.0ms | ActiveRecord: 119.3ms)  (0.1ms) begin transaction ---------------------------------------------------- OrdersControllerTest: test_checking_formie_copyright ---------------------------------------------------- Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.1ms) Rendered app/formies/templates/labelled.html.erb (0.1ms) Rendered app/formies/templates/l_text_field.html.erb (0.4ms) Rendered orders/index.html.erb within layouts/application (1.1ms) Completed 200 OK in 2ms (Views: 2.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ---------------------------------------------------- OrdersControllerTest: test_checking_formie_copyright ---------------------------------------------------- Processing by OrdersController#index as HTML Order Load (0.2ms) SELECT "orders".* FROM "orders" Rendered app/formies/application/copyright.html.erb (0.7ms) Rendered app/formies/templates/labelled.html.erb (0.9ms) Rendered app/formies/templates/l_text_field.html.erb (54.4ms) Rendered orders/index.html.erb within layouts/application (94.5ms) Completed 200 OK in 250ms (Views: 247.9ms | ActiveRecord: 0.4ms)  (0.1ms) rollback transaction SQL (161.4ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:20:57.232247"], ["updated_at", "2016-12-23 21:20:57.232247"]]  (118.2ms) commit transaction Started GET "/orders/new" for 127.0.0.1 at 2016-12-23 22:20:57 +0100 Processing by OrdersController#new as HTML Rendered app/formies/forms/builtins.html.erb (1.2ms) Rendered orders/_new.html.erb (23.9ms) Rendered orders/new.html.erb within layouts/application (30.1ms) Completed 200 OK in 33ms (Views: 32.3ms | ActiveRecord: 0.0ms) SQL (111.4ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:20:57.515569"], ["updated_at", "2016-12-23 21:20:57.515569"]]  (106.5ms) commit transaction Started GET "/orders" for 127.0.0.1 at 2016-12-23 22:20:57 +0100 Processing by OrdersController#index as HTML Order Load (0.3ms) SELECT "orders".* FROM "orders" Rendered app/formies/application/copyright.html.erb (0.1ms) Rendered app/formies/templates/labelled.html.erb (0.1ms) Rendered app/formies/templates/l_text_field.html.erb (94.4ms) Rendered orders/index.html.erb within layouts/application (222.9ms) Completed 200 OK in 226ms (Views: 224.9ms | ActiveRecord: 0.3ms) SQL (103.3ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:20:57.958873"], ["updated_at", "2016-12-23 21:20:57.958873"]]  (114.8ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" ORDER BY "orders"."id" ASC LIMIT 1 Started GET "/orders/164" for 127.0.0.1 at 2016-12-23 22:20:58 +0100 Processing by OrdersController#show as HTML Parameters: {"id"=>"164"}  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "hugo"], ["created_at", "2016-12-23 21:20:58.080779"], ["updated_at", "2016-12-23 21:20:58.080779"]]  (118.6ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 164]] Rendered app/formies/forms/l_text_field.html.erb (19.3ms) Rendered app/formies/forms/l_text_field.html.erb (1.2ms) Rendered app/formies/templates/hello.slim (25.0ms) Rendered orders/_form.html.erb (311.8ms) Rendered orders/show.html.erb within layouts/application (314.0ms) Completed 200 OK in 444ms (Views: 316.2ms | ActiveRecord: 119.3ms) ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" SQL (157.8ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:22:16.284960"], ["updated_at", "2016-12-23 21:22:16.284960"]]  (130.5ms) commit transaction Started GET "/orders/new" for 127.0.0.1 at 2016-12-23 22:22:16 +0100 Processing by OrdersController#new as HTML Rendered app/formies/forms/builtins.html.erb (0.6ms) Rendered orders/_new.html.erb (2.5ms) Rendered orders/new.html.erb within layouts/application (6.1ms) Completed 200 OK in 127ms (Views: 126.7ms | ActiveRecord: 0.0ms) SQL (130.5ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:22:16.719660"], ["updated_at", "2016-12-23 21:22:16.719660"]]  (114.7ms) commit transaction Started GET "/orders" for 127.0.0.1 at 2016-12-23 22:22:16 +0100 Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.7ms) Rendered app/formies/templates/labelled.html.erb (0.3ms) Rendered app/formies/templates/l_text_field.html.erb (2.2ms) Rendered orders/index.html.erb within layouts/application (9.6ms) Completed 200 OK in 12ms (Views: 11.3ms | ActiveRecord: 0.0ms) SQL (110.7ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:22:16.962861"], ["updated_at", "2016-12-23 21:22:16.962861"]]  (106.5ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" ORDER BY "orders"."id" ASC LIMIT 1 Started GET "/orders/168" for 127.0.0.1 at 2016-12-23 22:22:17 +0100 Processing by OrdersController#show as HTML Parameters: {"id"=>"168"}  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "hugo"], ["created_at", "2016-12-23 21:22:17.076327"], ["updated_at", "2016-12-23 21:22:17.076327"]]  (111.7ms) commit transaction Order Load (0.2ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 168]] Rendered app/formies/forms/l_text_field.html.erb (10.0ms) Rendered app/formies/forms/l_text_field.html.erb (0.5ms) Rendered app/formies/templates/hello.slim (11.8ms) Rendered orders/_form.html.erb (26.2ms) Rendered orders/show.html.erb within layouts/application (27.3ms) Completed 200 OK in 149ms (Views: 28.6ms | ActiveRecord: 112.3ms)  (0.1ms) begin transaction ---------------------------------------------------- OrdersControllerTest: test_checking_formie_copyright ---------------------------------------------------- Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.1ms) Rendered app/formies/templates/labelled.html.erb (0.1ms) Rendered app/formies/templates/l_text_field.html.erb (0.4ms) Rendered orders/index.html.erb within layouts/application (1.1ms) Completed 200 OK in 2ms (Views: 2.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" SQL (131.5ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:22:37.958237"], ["updated_at", "2016-12-23 21:22:37.958237"]]  (115.1ms) commit transaction Started GET "/orders/new" for 127.0.0.1 at 2016-12-23 22:22:38 +0100 Processing by OrdersController#new as HTML Rendered app/formies/forms/builtins.html.erb (0.6ms) Rendered orders/_new.html.erb (2.4ms) Rendered orders/new.html.erb within layouts/application (6.0ms) Completed 200 OK in 127ms (Views: 126.3ms | ActiveRecord: 0.0ms) SQL (114.5ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:22:38.360992"], ["updated_at", "2016-12-23 21:22:38.360992"]]  (114.6ms) commit transaction Started GET "/orders" for 127.0.0.1 at 2016-12-23 22:22:38 +0100 Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.3ms) Rendered app/formies/templates/labelled.html.erb (0.3ms) Rendered app/formies/templates/l_text_field.html.erb (2.2ms) Rendered orders/index.html.erb within layouts/application (6.9ms) Completed 200 OK in 9ms (Views: 8.6ms | ActiveRecord: 0.0ms) SQL (113.3ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:22:38.604154"], ["updated_at", "2016-12-23 21:22:38.604154"]]  (123.8ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" ORDER BY "orders"."id" ASC LIMIT 1 Started GET "/orders/172" for 127.0.0.1 at 2016-12-23 22:22:38 +0100 Processing by OrdersController#show as HTML Parameters: {"id"=>"172"}  (0.2ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "hugo"], ["created_at", "2016-12-23 21:22:38.735174"], ["updated_at", "2016-12-23 21:22:38.735174"]]  (119.8ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 172]] Rendered app/formies/forms/l_text_field.html.erb (9.9ms) Rendered app/formies/forms/l_text_field.html.erb (0.5ms) Rendered app/formies/templates/hello.slim (11.8ms) Rendered orders/_form.html.erb (26.2ms) Rendered orders/show.html.erb within layouts/application (28.2ms) Completed 200 OK in 159ms (Views: 29.9ms | ActiveRecord: 120.4ms)  (0.1ms) begin transaction ---------------------------------------------------- OrdersControllerTest: test_checking_formie_copyright ---------------------------------------------------- Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.1ms) Rendered app/formies/templates/labelled.html.erb (0.1ms) Rendered app/formies/templates/l_text_field.html.erb (0.3ms) Rendered orders/index.html.erb within layouts/application (1.0ms) Completed 200 OK in 2ms (Views: 1.9ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction ---------------------------------------------------- OrdersControllerTest: test_checking_formie_copyright ---------------------------------------------------- Processing by OrdersController#index as HTML Order Load (0.2ms) SELECT "orders".* FROM "orders" Rendered app/formies/application/copyright.html.erb (0.8ms) Rendered app/formies/templates/labelled.html.erb (0.8ms) Rendered app/formies/templates/l_text_field.html.erb (57.7ms) Rendered orders/index.html.erb within layouts/application (100.3ms) Completed 200 OK in 257ms (Views: 255.7ms | ActiveRecord: 0.4ms)  (0.1ms) rollback transaction SQL (157.1ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:23:13.621440"], ["updated_at", "2016-12-23 21:23:13.621440"]]  (133.6ms) commit transaction Started GET "/orders" for 127.0.0.1 at 2016-12-23 22:23:13 +0100 Processing by OrdersController#index as HTML Order Load (0.3ms) SELECT "orders".* FROM "orders" Rendered app/formies/application/copyright.html.erb (0.1ms) Rendered app/formies/templates/labelled.html.erb (0.1ms) Rendered app/formies/templates/l_text_field.html.erb (83.5ms) Rendered orders/index.html.erb within layouts/application (184.9ms) Completed 200 OK in 187ms (Views: 186.1ms | ActiveRecord: 0.3ms) SQL (136.0ms) DELETE FROM "orders"  (0.2ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:23:14.095258"], ["updated_at", "2016-12-23 21:23:14.095258"]]  (106.4ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" ORDER BY "orders"."id" ASC LIMIT 1 Started GET "/orders/175" for 127.0.0.1 at 2016-12-23 22:23:14 +0100 Processing by OrdersController#show as HTML Parameters: {"id"=>"175"}  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "hugo"], ["created_at", "2016-12-23 21:23:14.208880"], ["updated_at", "2016-12-23 21:23:14.208880"]]  (110.3ms) commit transaction Order Load (0.2ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 175]] Rendered app/formies/forms/l_text_field.html.erb (19.8ms) Rendered app/formies/forms/l_text_field.html.erb (1.0ms) Rendered app/formies/templates/hello.slim (29.4ms) Rendered orders/_form.html.erb (283.4ms) Rendered orders/show.html.erb within layouts/application (286.8ms) Completed 200 OK in 408ms (Views: 288.4ms | ActiveRecord: 110.8ms) SQL (110.9ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:23:14.731025"], ["updated_at", "2016-12-23 21:23:14.731025"]]  (124.2ms) commit transaction Started GET "/orders/new" for 127.0.0.1 at 2016-12-23 22:23:14 +0100 Processing by OrdersController#new as HTML Rendered app/formies/forms/builtins.html.erb (1.4ms) Rendered orders/_new.html.erb (49.9ms) Rendered orders/new.html.erb within layouts/application (52.0ms) Completed 200 OK in 55ms (Views: 54.6ms | ActiveRecord: 0.0ms) ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ---------------------------------------------------- OrdersControllerTest: test_checking_formie_copyright ---------------------------------------------------- Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.4ms) Rendered app/formies/templates/labelled.html.erb (0.3ms) Rendered app/formies/templates/l_text_field.html.erb (2.2ms) Rendered orders/index.html.erb within layouts/application (6.8ms) Completed 200 OK in 128ms (Views: 126.9ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction SQL (135.8ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:23:34.404068"], ["updated_at", "2016-12-23 21:23:34.404068"]]  (117.5ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" ORDER BY "orders"."id" ASC LIMIT 1 Started GET "/orders/178" for 127.0.0.1 at 2016-12-23 22:23:34 +0100 Processing by OrdersController#show as HTML Parameters: {"id"=>"178"}  (0.1ms) begin transaction SQL (0.1ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "hugo"], ["created_at", "2016-12-23 21:23:34.536181"], ["updated_at", "2016-12-23 21:23:34.536181"]]  (106.8ms) commit transaction Order Load (0.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 178]] Rendered app/formies/forms/l_text_field.html.erb (10.0ms) Rendered app/formies/forms/l_text_field.html.erb (0.5ms) Rendered app/formies/templates/hello.slim (11.8ms) Rendered orders/_form.html.erb (26.2ms) Rendered orders/show.html.erb within layouts/application (32.1ms) Completed 200 OK in 146ms (Views: 33.9ms | ActiveRecord: 107.3ms) SQL (110.1ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:23:34.795618"], ["updated_at", "2016-12-23 21:23:34.795618"]]  (106.4ms) commit transaction Started GET "/orders" for 127.0.0.1 at 2016-12-23 22:23:34 +0100 Processing by OrdersController#index as HTML Rendered app/formies/application/copyright.html.erb (0.1ms) Rendered app/formies/templates/labelled.html.erb (0.1ms) Rendered app/formies/templates/l_text_field.html.erb (0.7ms) Rendered orders/index.html.erb within layouts/application (1.8ms) Completed 200 OK in 4ms (Views: 3.5ms | ActiveRecord: 0.0ms) SQL (117.2ms) DELETE FROM "orders"  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "orders" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "Rumpelstilzchen"], ["created_at", "2016-12-23 21:23:35.030434"], ["updated_at", "2016-12-23 21:23:35.030434"]]  (114.8ms) commit transaction Started GET "/orders/new" for 127.0.0.1 at 2016-12-23 22:23:35 +0100 Processing by OrdersController#new as HTML Rendered app/formies/forms/builtins.html.erb (1.2ms) Rendered orders/_new.html.erb (4.7ms) Rendered orders/new.html.erb within layouts/application (6.7ms) Completed 200 OK in 9ms (Views: 8.8ms | ActiveRecord: 0.0ms)