(3.4ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL)   (0.1ms) select sqlite_version(*)  (3.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateJpAddressZipcodes (20160312045953)  (0.0ms) begin transaction  (1.0ms) CREATE TABLE "jp_address_zipcodes" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "zip" varchar NOT NULL, "prefecture" varchar NOT NULL, "city" varchar NOT NULL, "town" varchar)  (0.1ms) CREATE INDEX "index_jp_address_zipcodes_on_zip" ON "jp_address_zipcodes" ("zip") SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20160312045953"]]  (2.6ms) commit transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms)  SELECT sql FROM sqlite_master WHERE name='index_jp_address_zipcodes_on_zip' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='index_jp_address_zipcodes_on_zip' AND type='index'  Started GET "/" for 127.0.0.1 at 2016-03-12 14:03:53 +0900 Processing by Rails::WelcomeController#index as HTML Rendered /usr/local/rvm/gems/ruby-2.1.1/gems/railties-4.2.5.2/lib/rails/templates/rails/welcome/index.html.erb (13.9ms) Completed 200 OK in 66ms (Views: 65.5ms | ActiveRecord: 0.0ms) Started GET "/jp_address/zipcodes/search" for 127.0.0.1 at 2016-03-12 14:06:25 +0900 Processing by JpAddress::ZipcodesController#search as HTML Rendered /mnt/hgfs/source/jp_address/app/views/jp_address/zipcodes/search.html.erb within layouts/jp_address/application (0.3ms) Completed 200 OK in 405ms (Views: 404.6ms | ActiveRecord: 0.0ms) Started GET "/assets/jp_address/application.self-e80e8f2318043e8af94dddc2adad5a4f09739a8ebb323b3ab31cd71d45fd9113.css?body=1" for 127.0.0.1 at 2016-03-12 14:06:25 +0900 Started GET "/assets/jp_address/application.self-8f06a73c35179188914ab50e057157639fce1401c1cdca640ac9cec33746fc5b.js?body=1" for 127.0.0.1 at 2016-03-12 14:06:25 +0900 Started GET "/jp_address/zipcodes/search" for 127.0.0.1 at 2016-03-12 14:07:00 +0900 Processing by JpAddress::ZipcodesController#search as HTML JpAddress::Zipcode Load (6.8ms) SELECT "jp_address_zipcodes".* FROM "jp_address_zipcodes" WHERE "jp_address_zipcodes"."zip" IS NULL LIMIT 1 Rendered text template (0.0ms) Completed 200 OK in 126ms (Views: 15.9ms | ActiveRecord: 7.3ms) Started GET "/jp_address/zipcodes/search" for 127.0.0.1 at 2016-03-12 14:07:11 +0900 Processing by JpAddress::ZipcodesController#search as HTML JpAddress::Zipcode Load (0.5ms) SELECT "jp_address_zipcodes".* FROM "jp_address_zipcodes" WHERE "jp_address_zipcodes"."zip" IS NULL LIMIT 1 Rendered text template (0.0ms) Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.5ms) Started GET "/jp_address/zipcodes/search" for 127.0.0.1 at 2016-03-12 14:18:43 +0900 ActionController::RoutingError (No route matches [GET] "/jp_address/zipcodes/search"): actionpack (4.2.5.2) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (4.2.5.2) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.2.5.2) lib/rails/rack/logger.rb:38:in `call_app' railties (4.2.5.2) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.2.5.2) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.2.5.2) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.2.5.2) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.2.5.2) lib/rails/rack/logger.rb:20:in `call' actionpack (4.2.5.2) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.6.4) lib/rack/methodoverride.rb:22:in `call' rack (1.6.4) lib/rack/runtime.rb:18:in `call' activesupport (4.2.5.2) lib/active_support/cache/strategy/local_cache_middleware.rb:28:in `call' rack (1.6.4) lib/rack/lock.rb:17:in `call' actionpack (4.2.5.2) lib/action_dispatch/middleware/static.rb:116:in `call' rack (1.6.4) lib/rack/sendfile.rb:113:in `call' railties (4.2.5.2) lib/rails/engine.rb:518:in `call' railties (4.2.5.2) lib/rails/application.rb:165:in `call' rack (1.6.4) lib/rack/lock.rb:17:in `call' rack (1.6.4) lib/rack/content_length.rb:15:in `call' rack (1.6.4) lib/rack/handler/webrick.rb:88:in `service' /usr/local/rvm/rubies/ruby-2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:138:in `service' /usr/local/rvm/rubies/ruby-2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:94:in `run' /usr/local/rvm/rubies/ruby-2.1.1/lib/ruby/2.1.0/webrick/server.rb:295:in `block in start_thread' Rendered /usr/local/rvm/gems/ruby-2.1.1/gems/actionpack-4.2.5.2/lib/action_dispatch/middleware/templates/rescues/_trace.html.erb (1.0ms) Rendered /usr/local/rvm/gems/ruby-2.1.1/gems/actionpack-4.2.5.2/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.4ms) Rendered /usr/local/rvm/gems/ruby-2.1.1/gems/actionpack-4.2.5.2/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.1ms) Rendered /usr/local/rvm/gems/ruby-2.1.1/gems/actionpack-4.2.5.2/lib/action_dispatch/middleware/templates/routes/_table.html.erb (29.0ms) Rendered /usr/local/rvm/gems/ruby-2.1.1/gems/actionpack-4.2.5.2/lib/action_dispatch/middleware/templates/rescues/_request_and_response.html.erb (2.2ms) Rendered /usr/local/rvm/gems/ruby-2.1.1/gems/actionpack-4.2.5.2/lib/action_dispatch/middleware/templates/rescues/routing_error.html.erb within rescues/layout (55.6ms) Started GET "/jp_address/zipcodes/search" for 127.0.0.1 at 2016-03-12 14:18:46 +0900 Processing by JpAddress::ZipcodesController#search as HTML JpAddress::Zipcode Load (0.3ms) SELECT "jp_address_zipcodes".* FROM "jp_address_zipcodes" WHERE "jp_address_zipcodes"."zip" IS NULL LIMIT 1 Rendered text template (0.0ms) Completed 200 OK in 10ms (Views: 2.1ms | ActiveRecord: 0.5ms)