(0.1ms) begin transaction -------------------------- BotBuilderTest: test_truth --------------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/notify" for 127.0.0.1 at 2016-01-15 14:04:23 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction -------------------------- BotBuilderTest: test_truth --------------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction -------------------------- BotBuilderTest: test_truth --------------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/kik-notify" for 127.0.0.1 at 2016-01-15 14:05:47 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/botbuilder/kik-notify" for 127.0.0.1 at 2016-01-15 14:06:24 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction -------------------------- BotBuilderTest: test_truth --------------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot_builder/kik-notify" for 127.0.0.1 at 2016-01-15 14:06:35 -0500 Processing by BotBuilder::KikController#notify as HTML Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction -------------------------- BotBuilderTest: test_truth --------------------------  (0.0ms) rollback transaction  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:14:00 -0500 Processing by Bot::KikController#notify as HTML Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:14:41 -0500 Processing by Bot::KikController#notify as HTML Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:15:07 -0500 Processing by Bot::KikController#notify as HTML Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:20:26 -0500 Processing by Bot::KikController#notify as HTML Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:22:33 -0500 Processing by Bot::KikController#notify as HTML Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:22:57 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:24:49 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:24:49 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "kik"=>{"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]}} [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:24:59 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:24:59 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "kik"=>{"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]}} [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:25:17 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:25:17 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "kik"=>{"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]}} [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.2ms) rollback transaction  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:25:36 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-15 14:25:36 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "kik"=>{"messages"=>[{"body"=>"Double cup please", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]}} [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-18 10:57:34 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-18 10:57:34 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]} [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-18 10:58:06 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-18 10:58:06 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]} [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-18 10:58:15 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-18 10:58:15 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]} [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-19 17:12:15 -0500 Processing by Bot::KikController#notify as HTML [] Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-19 17:12:15 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}]} [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-19 17:31:35 -0500 Processing by Bot::KikController#notify as HTML Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-19 17:52:57 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-19 18:00:33 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.2ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/kik-notify" for 127.0.0.1 at 2016-01-19 18:02:58 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:03:52 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:04:08 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:04:34 -0500  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:04:48 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} [] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:04:48 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:09:22 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:09:22 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:11:11 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:11:11 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:11:24 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:11:24 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:11:39 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:11:39 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:11:49 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:12:16 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:12:17 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:12:29 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:13:21 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:13:21 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:14:38 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:14:53 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:14:53 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:15:43 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:15:43 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)  (1.7ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:08 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:08 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:23 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:23 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.2ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:35 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:35 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:54 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:16:54 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:19:47 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-19 18:19:47 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.2ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 11:02:46 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 11:02:46 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.0ms)  (0.2ms) rollback transaction ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.4ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:18:25 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:18:25 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.5ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:19:45 -0500  (0.3ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.5ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:19:50 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:19:50 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.2ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:22:03 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:22:27 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:22:27 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.1ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:23:40 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:23:40 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:24:17 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:24:17 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Sending: [{"type":"text","to":"bnmrrs","body":"Hello!","typeTime":0}] Completed 500 Internal Server Error in 3ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.4ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:26:27 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:26:27 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Sending: [{"type":"text","to":"bnmrrs","body":"Hello!","typeTime":0}] Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.4ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:27:11 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:27:11 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Sending: [{"type":"text","to":"bnmrrs","body":"Hello!","typeTime":0}] Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:49:45 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:49:45 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Sending: [{"type":"text","to":"bnmrrs","body":"Hello!","typeTime":0}] Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.0ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:52:39 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:52:39 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Sending: [{"type":"text","to":"bnmrrs","body":"Hello!","typeTime":0}] Completed 500 Internal Server Error in 3ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:55:18 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:55:18 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Sending: [{"type":"text","to":"bnmrrs","body":"Hello!","typeTime":0}] Completed 500 Internal Server Error in 3ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.0ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:57:01 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:57:01 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Sending: [{"type":"text","to":"bnmrrs","body":"Hello!","typeTime":0}] Completed 500 Internal Server Error in 1155ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.6ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:57:40 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:57:40 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:59:32 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 13:59:32 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:01:13 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:01:13 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:02:10 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:02:10 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:02:27 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:02:27 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (4.7ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:03:31 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:03:31 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:03:43 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:03:43 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:04:20 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:04:20 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:04:29 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:04:29 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:05:44 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:05:44 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.3ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:06:17 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 14:06:17 -0500 Processing by Bot::KikController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 15:13:32 -0500  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 15:14:23 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 15:14:23 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 15:47:13 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Using ApplicationHandler [] Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 15:47:13 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Using ApplicationHandler [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 15:59:29 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} [] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 15:59:29 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} [{"type"=>"text", "to"=>"bnmrrs", "body"=>"Hello!", "typeTime"=>0}] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.0ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 17:31:27 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-20 17:31:27 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-21 13:48:12 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-21 13:48:12 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.2ms) rollback transaction ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.0ms) select sqlite_version(*)  (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 10:54:36 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 10:54:36 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.6ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 10:56:01 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 10:56:01 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.0ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:00:17 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:00:17 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 500 Internal Server Error in 3ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.6ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:00:28 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:00:28 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:06 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:06 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 500 Internal Server Error in 3ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.0ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:20 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:20 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:37 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:37 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 500 Internal Server Error in 3ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:50 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:03:50 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:04:02 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:04:02 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:06:52 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:06:52 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:06:52 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.1ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.0ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:07:33 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:07:33 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:07:33 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.6ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:08:53 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:08:54 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:08:54 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.1ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:09:56 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:09:56 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.4ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:09:56 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.1ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:06 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:06 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:06 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 8ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:10 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:10 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:10 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.6ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:19 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:19 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:19 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:39 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:39 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:39 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.7ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:53 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:53 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:10:53 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:13:12 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:13:12 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:13:12 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 9ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.2ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:30 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:30 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:30 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:30 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:46 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:46 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:46 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:21:46 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.0ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:22:32 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:22:32 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:22:32 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-01-27 11:22:32 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-02-24 14:09:47 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-02-24 14:09:47 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-02-24 14:09:47 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-02-24 14:09:47 -0500 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-29 10:22:30 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-03-29 10:22:30 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-29 10:22:30 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-29 10:22:30 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.4ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.2ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.1ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 10:47:22 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 10:47:22 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 10:47:22 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 10:47:22 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (13.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (18.3ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 13:38:55 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 13:38:55 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 13:38:55 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-03-30 13:38:55 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.7ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-06 15:50:27 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-06 15:50:27 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-06 15:50:27 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-06 15:50:27 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-07 14:29:37 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-07 14:29:37 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-07 14:29:37 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-07 14:29:37 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-11 11:44:14 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-11 11:44:14 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.2ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-11 11:44:14 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-11 11:44:14 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (5.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.1ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (1.0ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:07:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:07:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:07:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:07:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.4ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:08:02 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:08:02 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:08:02 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-13 15:08:02 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-17 11:38:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-17 11:38:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-17 11:38:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-17 11:38:44 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-18 16:40:23 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-18 16:40:23 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-18 16:40:23 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-18 16:40:23 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 10:15:25 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.3ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 10:15:25 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 10:15:25 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 10:15:25 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 14:45:54 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 14:45:54 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 14:45:54 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-21 14:45:54 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.8ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:12:56 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:12:56 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:12:56 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:12:56 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (2.4ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (1.5ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:20 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:20 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:20 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:20 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.5ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (0.9ms) INSERT INTO "schema_migrations" (version) VALUES ('0') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms) begin transaction ------------------- BotTest: test_truth -------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction --------------------------------------- NavigationTest: test_notify_multi_types --------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:47 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"multi", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction ----------------------------------------- NavigationTest: test_notify_special_types ----------------------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:47 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"scan-data", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction --------------------------- NavigationTest: test_notify --------------------------- Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:47 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started POST "/bot/notify" for 127.0.0.1 at 2016-04-27 21:49:47 -0400 Processing by Bot::BotController#notify as HTML Parameters: {"messages"=>[{"body"=>"Hello", "readReceiptRequested"=>"true", "from"=>"bnmrrs", "timestamp"=>"1452785908454", "type"=>"text", "id"=>"2af0d873-d157-4627-b863-8be11b0dfd86"}], "bot"=>ApplicationHandler} Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction