Started GET "/" for ::1 at 2017-12-15 12:56:40 +0900 ActiveRecord::PendingMigrationError ( Migrations are pending. To resolve this issue, run: bin/rails db:migrate RAILS_ENV=development ): activerecord (5.1.4) lib/active_record/migration.rb:576:in `check_pending!' activerecord (5.1.4) lib/active_record/migration.rb:553:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.4.1/lib/ruby/2.4.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.4.1/lib/ruby/2.4.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.4.1/lib/ruby/2.4.0/webrick/server.rb:290:in `block in start_thread'  (1.7ms) SELECT sqlite_version(*)  (1.5ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)  (1.2ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Migrating to CreateItems (20131223124841)  (0.0ms) begin transaction  (0.3ms) CREATE TABLE "items" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar, "sort" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL) SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20131223124841"]]  (1.1ms) commit transaction Migrating to CreateSequencedItems (20170414031946)  (0.1ms) begin transaction  (0.4ms) CREATE TABLE "sequenced_items" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar, "sequence" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL) SQL (0.2ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20170414031946"]]  (0.9ms) commit transaction ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction SQL (0.3ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "development"], ["created_at", "2017-12-15 03:57:19.617937"], ["updated_at", "2017-12-15 03:57:19.617937"]]  (0.6ms) commit transaction  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Started GET "/" for ::1 at 2017-12-15 12:57:45 +0900  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Processing by Rails::WelcomeController#index as HTML Rendering /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.4.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb Rendered /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.4.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb (2.2ms) Completed 200 OK in 8ms (Views: 5.1ms | ActiveRecord: 0.0ms) Started GET "/" for ::1 at 2017-12-16 20:18:08 +0900  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Processing by Rails::WelcomeController#index as HTML Rendering /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.4.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb Rendered /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.4.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb (3.6ms) Completed 200 OK in 13ms (Views: 7.2ms | ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2017-12-16 20:18:12 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (1.7ms) Completed 200 OK in 400ms (Views: 394.6ms | ActiveRecord: 0.3ms) Started GET "/assets/application.self-f9e7c1541e1b8783561468c59162bd896007380f5a3799ef2169d3a3fdf40bed.css?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/scaffold.self-83b741db49389dc7cfdf85bf7537a0219cce48e085c4116afc83d55c9af47c78.css?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery.self-bd7ddd393353a8d2480a622e80342adf488fb6006d667e8b42e4c0073393abee.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery_ujs.self-784a997f6726036b1993eb2217c9cb558e1cbb801c6da88105588c56f13b466a.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery-ui/version.self-c8e3d1203da26ea7efdf83c1eabb3f0ba55cb68e463f5ccf0d77bd15ce6a8e61.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery-ui/widgets/mouse.self-f0790a202dcf54fab051e71b042335d5346f9cceb0f645223c1dab93f349a04b.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery-ui/ie.self-163eb039dcb1e0c3471b7d91386eabb8753da6906e84824c019f9ff56bdb83a6.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery-ui/widget.self-fca20bcec06d192f97cffa6e734e24360e227237b8ae7d7e7e60754df7d5444f.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery-ui/data.self-2067c82e6fe39a9cba5623206a5dc28d5c60d26b95cd100855b78cacf72865b8.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery-ui/scroll-parent.self-38e26a71a450a29fb63425e8f589a7046862f927eb0a1dbf6a6c44da05196d2e.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/plugin.self-84bdbacb1efd558ee0dac9e9a39c3f2acc7a75ef4b01112739eec325ddfa2429.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/rails_sortable.self-e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/sequenced_items.self-3f5ee897a5a4a74ee2d01575104d7e38daf09a55678ea0eec89cd57028db5471.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/jquery-ui/widgets/sortable.self-6e5e1f07a29b5a89ec1d359363d2800904e3ae548a38ab2f8eded36746d1adbe.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/application.self-120f3cf1cb67bea53c4a2a57336b34ca04b04b55a55c38cf76ce20a147e5eff9.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900 Started GET "/assets/items.self-3f5ee897a5a4a74ee2d01575104d7e38daf09a55678ea0eec89cd57028db5471.js?body=1" for ::1 at 2017-12-16 20:18:12 +0900  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (0.0ms) begin transaction SQL (0.3ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title0"], ["sort", 0], ["created_at", "2017-12-16 11:19:06.102946"], ["updated_at", "2017-12-16 11:19:06.102946"]]  (1.1ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title1"], ["sort", 1], ["created_at", "2017-12-16 11:19:06.105613"], ["updated_at", "2017-12-16 11:19:06.105613"]]  (1.2ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title2"], ["sort", 2], ["created_at", "2017-12-16 11:19:06.108048"], ["updated_at", "2017-12-16 11:19:06.108048"]]  (0.8ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title3"], ["sort", 3], ["created_at", "2017-12-16 11:19:06.109965"], ["updated_at", "2017-12-16 11:19:06.109965"]]  (0.5ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title4"], ["sort", 4], ["created_at", "2017-12-16 11:19:06.111703"], ["updated_at", "2017-12-16 11:19:06.111703"]]  (0.8ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title5"], ["sort", 5], ["created_at", "2017-12-16 11:19:06.113596"], ["updated_at", "2017-12-16 11:19:06.113596"]]  (0.6ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title6"], ["sort", 6], ["created_at", "2017-12-16 11:19:06.115153"], ["updated_at", "2017-12-16 11:19:06.115153"]]  (0.5ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title7"], ["sort", 7], ["created_at", "2017-12-16 11:19:06.116699"], ["updated_at", "2017-12-16 11:19:06.116699"]]  (1.0ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title8"], ["sort", 8], ["created_at", "2017-12-16 11:19:06.118808"], ["updated_at", "2017-12-16 11:19:06.118808"]]  (0.7ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "items" ("title", "sort", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "Title9"], ["sort", 9], ["created_at", "2017-12-16 11:19:06.120688"], ["updated_at", "2017-12-16 11:19:06.120688"]]  (0.6ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle0"], ["sequence", 0], ["created_at", "2017-12-16 11:19:06.126822"], ["updated_at", "2017-12-16 11:19:06.126822"]]  (0.7ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle1"], ["sequence", 1], ["created_at", "2017-12-16 11:19:06.128673"], ["updated_at", "2017-12-16 11:19:06.128673"]]  (0.5ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle2"], ["sequence", 2], ["created_at", "2017-12-16 11:19:06.130237"], ["updated_at", "2017-12-16 11:19:06.130237"]]  (1.1ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle3"], ["sequence", 3], ["created_at", "2017-12-16 11:19:06.132577"], ["updated_at", "2017-12-16 11:19:06.132577"]]  (0.7ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle4"], ["sequence", 4], ["created_at", "2017-12-16 11:19:06.134454"], ["updated_at", "2017-12-16 11:19:06.134454"]]  (0.5ms) commit transaction  (0.0ms) begin transaction SQL (0.1ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle5"], ["sequence", 5], ["created_at", "2017-12-16 11:19:06.136026"], ["updated_at", "2017-12-16 11:19:06.136026"]]  (0.7ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle6"], ["sequence", 6], ["created_at", "2017-12-16 11:19:06.137901"], ["updated_at", "2017-12-16 11:19:06.137901"]]  (0.5ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle7"], ["sequence", 7], ["created_at", "2017-12-16 11:19:06.139493"], ["updated_at", "2017-12-16 11:19:06.139493"]]  (0.6ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle8"], ["sequence", 8], ["created_at", "2017-12-16 11:19:06.141010"], ["updated_at", "2017-12-16 11:19:06.141010"]]  (0.7ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "sequenced_items" ("title", "sequence", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["title", "OtherTitle9"], ["sequence", 9], ["created_at", "2017-12-16 11:19:06.142828"], ["updated_at", "2017-12-16 11:19:06.142828"]]  (0.7ms) commit transaction Started GET "/items" for ::1 at 2017-12-16 20:19:08 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (8.3ms) Completed 200 OK in 43ms (Views: 41.0ms | ActiveRecord: 0.4ms) Started POST "/sortable/reorder" for ::1 at 2017-12-16 20:19:13 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["7", "1", "2", "3", "4", "5", "6", "8", "9", "10"]} Item Load (0.3ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC  (0.0ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2017-12-16 11:19:13.054219"], ["id", 7]]  (2.4ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2017-12-16 11:19:13.058739"], ["id", 1]]  (1.0ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2017-12-16 11:19:13.061379"], ["id", 2]]  (0.7ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2017-12-16 11:19:13.063859"], ["id", 3]]  (0.6ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2017-12-16 11:19:13.065814"], ["id", 4]]  (1.1ms) commit transaction  (0.1ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2017-12-16 11:19:13.068752"], ["id", 5]]  (0.6ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2017-12-16 11:19:13.070912"], ["id", 6]]  (0.7ms) commit transaction Completed 200 OK in 21ms (ActiveRecord: 9.4ms) Started POST "/sortable/reorder" for ::1 at 2017-12-16 20:19:15 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["7", "4", "1", "2", "3", "5", "6", "8", "9", "10"]} Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2017-12-16 11:19:15.208316"], ["id", 4]]  (2.1ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2017-12-16 11:19:15.212156"], ["id", 1]]  (0.5ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2017-12-16 11:19:15.214347"], ["id", 2]]  (0.8ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2017-12-16 11:19:15.216508"], ["id", 3]]  (0.6ms) commit transaction Completed 200 OK in 12ms (ActiveRecord: 5.3ms) Started GET "/items" for ::1 at 2017-12-16 20:19:18 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.5ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (3.6ms) Completed 200 OK in 29ms (Views: 26.7ms | ActiveRecord: 0.5ms) Started GET "/" for ::1 at 2018-02-16 16:25:09 +0900  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Processing by Rails::WelcomeController#index as HTML Rendering /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.5.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb Rendered /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.5.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb (2.9ms) Completed 200 OK in 9ms (Views: 7.2ms | ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2018-02-16 16:25:13 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (8.1ms) Completed 200 OK in 357ms (Views: 351.4ms | ActiveRecord: 0.6ms) Started GET "/assets/scaffold.self-83b741db49389dc7cfdf85bf7537a0219cce48e085c4116afc83d55c9af47c78.css?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/application.self-f9e7c1541e1b8783561468c59162bd896007380f5a3799ef2169d3a3fdf40bed.css?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery.self-bd7ddd393353a8d2480a622e80342adf488fb6006d667e8b42e4c0073393abee.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery_ujs.self-784a997f6726036b1993eb2217c9cb558e1cbb801c6da88105588c56f13b466a.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery-ui/version.self-c8e3d1203da26ea7efdf83c1eabb3f0ba55cb68e463f5ccf0d77bd15ce6a8e61.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery-ui/ie.self-163eb039dcb1e0c3471b7d91386eabb8753da6906e84824c019f9ff56bdb83a6.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery-ui/widget.self-fca20bcec06d192f97cffa6e734e24360e227237b8ae7d7e7e60754df7d5444f.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery-ui/widgets/mouse.self-f0790a202dcf54fab051e71b042335d5346f9cceb0f645223c1dab93f349a04b.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery-ui/data.self-2067c82e6fe39a9cba5623206a5dc28d5c60d26b95cd100855b78cacf72865b8.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery-ui/scroll-parent.self-38e26a71a450a29fb63425e8f589a7046862f927eb0a1dbf6a6c44da05196d2e.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/jquery-ui/widgets/sortable.self-6e5e1f07a29b5a89ec1d359363d2800904e3ae548a38ab2f8eded36746d1adbe.js?body=1" for ::1 at 2018-02-16 16:25:13 +0900 Started GET "/assets/plugin.self-84bdbacb1efd558ee0dac9e9a39c3f2acc7a75ef4b01112739eec325ddfa2429.js?body=1" for ::1 at 2018-02-16 16:25:14 +0900 Started GET "/assets/rails_sortable.self-e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855.js?body=1" for ::1 at 2018-02-16 16:25:14 +0900 Started GET "/assets/items.self-3f5ee897a5a4a74ee2d01575104d7e38daf09a55678ea0eec89cd57028db5471.js?body=1" for ::1 at 2018-02-16 16:25:14 +0900 Started GET "/assets/sequenced_items.self-3f5ee897a5a4a74ee2d01575104d7e38daf09a55678ea0eec89cd57028db5471.js?body=1" for ::1 at 2018-02-16 16:25:14 +0900 Started GET "/assets/application.self-120f3cf1cb67bea53c4a2a57336b34ca04b04b55a55c38cf76ce20a147e5eff9.js?body=1" for ::1 at 2018-02-16 16:25:14 +0900 Started POST "/sortable/reorder" for ::1 at 2018-02-16 16:25:19 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "7", "4", "2", "3", "5", "6", "8", "9", "10"]} Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 7, 4, 2, 3, 5, 6, 8, 9, 10) ORDER BY "items"."sort" ASC  (0.0ms) begin transaction SQL (0.5ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-02-16 07:25:19.400928"], ["id", 1]]  (1.8ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-02-16 07:25:19.404795"], ["id", 7]]  (1.0ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-02-16 07:25:19.407208"], ["id", 4]]  (1.2ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 5.4ms) Started POST "/sortable/reorder" for ::1 at 2018-02-16 16:25:22 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "7", "4", "3", "5", "6", "8", "9", "10"]} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 7, 4, 3, 5, 6, 8, 9, 10) ORDER BY "items"."sort" ASC  (0.0ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-02-16 07:25:22.215357"], ["id", 2]]  (0.7ms) commit transaction  (0.1ms) begin transaction SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-02-16 07:25:22.217623"], ["id", 7]]  (0.6ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-02-16 07:25:22.219932"], ["id", 4]]  (1.0ms) commit transaction Completed 200 OK in 8ms (ActiveRecord: 3.6ms) Started POST "/sortable/reorder" for ::1 at 2018-02-16 16:25:24 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "3", "7", "4", "5", "6", "8", "9", "10"]} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 7, 4, 5, 6, 8, 9, 10) ORDER BY "items"."sort" ASC  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-02-16 07:25:24.042044"], ["id", 3]]  (2.3ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-02-16 07:25:24.046020"], ["id", 7]]  (0.9ms) commit transaction  (0.1ms) begin transaction SQL (0.5ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-02-16 07:25:24.048729"], ["id", 4]]  (0.8ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 5.5ms) Started POST "/sortable/reorder" for ::1 at 2018-02-16 16:25:25 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "3", "4", "7", "5", "6", "8", "9", "10"]} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 7, 5, 6, 8, 9, 10) ORDER BY "items"."sort" ASC  (0.0ms) begin transaction SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-02-16 07:25:25.222388"], ["id", 4]]  (2.1ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-02-16 07:25:25.225974"], ["id", 7]]  (0.8ms) commit transaction Completed 200 OK in 7ms (ActiveRecord: 3.7ms) Started POST "/sortable/reorder" for ::1 at 2018-02-16 16:25:26 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "3", "4", "5", "7", "6", "8", "9", "10"]} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 7, 6, 8, 9, 10) ORDER BY "items"."sort" ASC  (0.0ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-02-16 07:25:26.345063"], ["id", 5]]  (2.2ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-02-16 07:25:26.348864"], ["id", 7]]  (0.7ms) commit transaction Completed 200 OK in 8ms (ActiveRecord: 3.8ms) Started POST "/sortable/reorder" for ::1 at 2018-02-16 16:25:27 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "3", "4", "5", "6", "7", "8", "9", "10"]} Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10) ORDER BY "items"."sort" ASC  (0.1ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-02-16 07:25:27.472665"], ["id", 6]]  (2.4ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-02-16 07:25:27.476698"], ["id", 7]]  (1.1ms) commit transaction Completed 200 OK in 9ms (ActiveRecord: 4.4ms) Started GET "/items" for ::1 at 2018-02-16 16:25:30 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.8ms) Completed 200 OK in 23ms (Views: 20.9ms | ActiveRecord: 0.2ms) Started GET "/" for ::1 at 2018-03-17 18:58:47 +0900  (0.8ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Processing by Rails::WelcomeController#index as HTML Rendering /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.5.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb Rendered /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.5.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb (4.1ms) Completed 200 OK in 23ms (Views: 12.2ms | ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2018-03-17 18:58:52 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.8ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (8.7ms) Completed 200 OK in 332ms (Views: 326.8ms | ActiveRecord: 1.2ms) Started GET "/assets/application.self-f9e7c1541e1b8783561468c59162bd896007380f5a3799ef2169d3a3fdf40bed.css?body=1" for ::1 at 2018-03-17 18:58:52 +0900 Started GET "/assets/scaffold.self-83b741db49389dc7cfdf85bf7537a0219cce48e085c4116afc83d55c9af47c78.css?body=1" for ::1 at 2018-03-17 18:58:52 +0900 Started GET "/assets/jquery.self-bd7ddd393353a8d2480a622e80342adf488fb6006d667e8b42e4c0073393abee.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery_ujs.self-784a997f6726036b1993eb2217c9cb558e1cbb801c6da88105588c56f13b466a.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery-ui/version.self-c8e3d1203da26ea7efdf83c1eabb3f0ba55cb68e463f5ccf0d77bd15ce6a8e61.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery-ui/ie.self-163eb039dcb1e0c3471b7d91386eabb8753da6906e84824c019f9ff56bdb83a6.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery-ui/widget.self-fca20bcec06d192f97cffa6e734e24360e227237b8ae7d7e7e60754df7d5444f.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery-ui/widgets/mouse.self-f0790a202dcf54fab051e71b042335d5346f9cceb0f645223c1dab93f349a04b.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery-ui/data.self-2067c82e6fe39a9cba5623206a5dc28d5c60d26b95cd100855b78cacf72865b8.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery-ui/scroll-parent.self-38e26a71a450a29fb63425e8f589a7046862f927eb0a1dbf6a6c44da05196d2e.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/jquery-ui/widgets/sortable.self-6e5e1f07a29b5a89ec1d359363d2800904e3ae548a38ab2f8eded36746d1adbe.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/items.self-3f5ee897a5a4a74ee2d01575104d7e38daf09a55678ea0eec89cd57028db5471.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/sequenced_items.self-3f5ee897a5a4a74ee2d01575104d7e38daf09a55678ea0eec89cd57028db5471.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/rails_sortable.self-e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/application.self-120f3cf1cb67bea53c4a2a57336b34ca04b04b55a55c38cf76ce20a147e5eff9.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/assets/plugin.self-8211650e7f8610773fba42d71443e831b2895bd3d41e63d220f0d8cf3ada1e96.js?body=1" for ::1 at 2018-03-17 18:58:53 +0900 Started GET "/items" for ::1 at 2018-03-17 18:58:57 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.5ms) Completed 200 OK in 23ms (Views: 20.9ms | ActiveRecord: 0.1ms) Started GET "/items" for ::1 at 2018-03-17 18:59:19 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.3ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.9ms) Completed 200 OK in 22ms (Views: 20.1ms | ActiveRecord: 0.3ms) Started GET "/items" for ::1 at 2018-03-17 19:01:51 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.6ms) Completed 200 OK in 76ms (Views: 74.4ms | ActiveRecord: 0.1ms) Started GET "/assets/plugin.self-7a7940dd15ae98b294e8ed7a98aacaefdeeb2e759677094bcca792cd40f4d826.js?body=1" for ::1 at 2018-03-17 19:01:51 +0900 Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:01:53 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["10", "1", "2", "3", "4", "5", "6", "7", "8", "9"]}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (10, 1, 2, 3, 4, 5, 6, 7, 8, 9) ORDER BY "items"."sort" ASC SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:01:53.495214"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:01:53.496320"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:01:53.497046"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:01:53.497692"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:01:53.498306"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:01:53.498857"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:01:53.499491"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:01:53.500096"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:01:53.500692"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:01:53.501260"], ["id", 9]]  (2.3ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 3.5ms) Started GET "/items" for ::1 at 2018-03-17 19:01:55 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.8ms) Completed 200 OK in 27ms (Views: 24.5ms | ActiveRecord: 0.2ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:01:57 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "3", "4", "5", "6", "7", "8", "9", "10"]}  (0.0ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:01:57.585751"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:01:57.586675"], ["id", 2]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:01:57.587286"], ["id", 3]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:01:57.587880"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:01:57.588502"], ["id", 5]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:01:57.589078"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:01:57.589698"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:01:57.590506"], ["id", 8]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:01:57.591147"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:01:57.591876"], ["id", 10]]  (1.5ms) commit transaction Completed 200 OK in 10ms (ActiveRecord: 2.5ms) Started GET "/items" for ::1 at 2018-03-17 19:01:58 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.3ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (4.1ms) Completed 200 OK in 28ms (Views: 26.0ms | ActiveRecord: 0.3ms) Started GET "/items" for ::1 at 2018-03-17 19:06:13 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (4.2ms) Completed 200 OK in 89ms (Views: 86.9ms | ActiveRecord: 0.2ms) Started GET "/assets/items.self-f1e606000df03307b2de9da3ca82afc5b543b105855f946d80d57dc8caa6c7e4.js?body=1" for ::1 at 2018-03-17 19:06:13 +0900 Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:06:17 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["10", "1", "2", "3", "4", "5", "6", "7", "8", "9"]}  (0.0ms) begin transaction Item Load (0.1ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (10, 1, 2, 3, 4, 5, 6, 7, 8, 9) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:06:17.609816"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:06:17.613391"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:06:17.614156"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:06:17.614784"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:06:17.615434"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:06:17.616472"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:06:17.617155"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:06:17.617899"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:06:17.618858"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:06:17.620220"], ["id", 9]]  (1.8ms) commit transaction Completed 200 OK in 15ms (ActiveRecord: 2.9ms) Started GET "/items" for ::1 at 2018-03-17 19:06:28 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.9ms) Completed 200 OK in 28ms (Views: 26.7ms | ActiveRecord: 0.2ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:06:32 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["9", "10", "1", "2", "3", "4", "5", "6", "7", "8"]}  (0.0ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (9, 10, 1, 2, 3, 4, 5, 6, 7, 8) ORDER BY "items"."sort" ASC SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:06:32.583754"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:06:32.584636"], ["id", 10]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:06:32.585183"], ["id", 1]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:06:32.585803"], ["id", 2]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:06:32.586312"], ["id", 3]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:06:32.586929"], ["id", 4]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:06:32.587609"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:06:32.588153"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:06:32.588867"], ["id", 7]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:06:32.589673"], ["id", 8]]  (2.0ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 3.1ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:06:35 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["9", "1", "2", "3", "4", "5", "6", "7", "8", "10"]}  (0.0ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (9, 1, 2, 3, 4, 5, 6, 7, 8, 10) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:06:35.099201"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:06:35.100143"], ["id", 2]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:06:35.100806"], ["id", 3]] SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:06:35.101529"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:06:35.102770"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:06:35.103453"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:06:35.104200"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:06:35.105171"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:06:35.105945"], ["id", 10]]  (2.0ms) commit transaction Completed 200 OK in 12ms (ActiveRecord: 3.2ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:06:37 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "3", "4", "5", "6", "7", "8", "9", "10"]}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10) ORDER BY "items"."sort" ASC SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:06:37.343502"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:06:37.344676"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:06:37.345538"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:06:37.346163"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:06:37.346895"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:06:37.347579"], ["id", 6]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:06:37.348293"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:06:37.349097"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:06:37.350112"], ["id", 9]]  (1.9ms) commit transaction Completed 200 OK in 13ms (ActiveRecord: 3.1ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:06:59 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["10", "1", "2", "3", "4", "5", "6", "7", "8", "9"]}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (10, 1, 2, 3, 4, 5, 6, 7, 8, 9) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:06:59.561736"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:06:59.563014"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:06:59.564008"], ["id", 2]] SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:06:59.564609"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:06:59.566180"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:06:59.567194"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:06:59.568103"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:06:59.568830"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:06:59.569376"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:06:59.570125"], ["id", 9]]  (2.1ms) commit transaction Completed 200 OK in 14ms (ActiveRecord: 3.5ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:07:01 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "2", "3", "4", "5", "6", "7", "8", "9", "10"]}  (0.0ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:07:01.013207"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:07:01.014302"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:07:01.015271"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:07:01.015973"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:07:01.017004"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:07:01.018026"], ["id", 6]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:07:01.018818"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:07:01.019624"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:07:01.020184"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:07:01.020960"], ["id", 10]]  (2.4ms) commit transaction Completed 200 OK in 13ms (ActiveRecord: 3.6ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:07:29 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["2", "3", "4", "5", "6", "7", "8", "9", "10", "1"]}  (0.0ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (2, 3, 4, 5, 6, 7, 8, 9, 10, 1) ORDER BY "items"."sort" ASC SQL (0.6ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:07:29.464922"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:07:29.466483"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:07:29.467274"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:07:29.468049"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:07:29.468744"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:07:29.469641"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:07:29.470740"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:07:29.471806"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:07:29.473110"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:07:29.474792"], ["id", 1]]  (1.2ms) commit transaction Completed 200 OK in 15ms (ActiveRecord: 2.9ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:07:53 +0900 Started GET "/items" for ::1 at 2018-03-17 19:07:53 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["3", "4", "5", "6", "2", "7", "8", "9", "10", "1"]}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (3, 4, 5, 6, 2, 7, 8, 9, 10, 1) ORDER BY "items"."sort" ASC Processing by ItemsController#index as HTML SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:07:53.400683"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:07:53.403642"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:07:53.407376"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:07:53.409154"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:07:53.410447"], ["id", 2]]  (2.3ms) commit transaction Completed 200 OK in 19ms (ActiveRecord: 3.3ms) Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (5.3ms) Completed 200 OK in 51ms (Views: 47.4ms | ActiveRecord: 0.2ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:08:57 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["3", "4", "1", "5", "6", "2", "7", "8", "9", "10"]}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (3, 4, 1, 5, 6, 2, 7, 8, 9, 10) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:08:57.395845"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:08:57.396700"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:08:57.397347"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:08:57.397973"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:08:57.398606"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:08:57.399427"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:08:57.400130"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:08:57.400872"], ["id", 10]]  (1.0ms) commit transaction Completed 200 OK in 9ms (ActiveRecord: 1.9ms) Started GET "/items" for ::1 at 2018-03-17 19:12:35 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.9ms) Completed 200 OK in 27ms (Views: 24.8ms | ActiveRecord: 0.2ms) Started GET "/items" for ::1 at 2018-03-17 19:17:04 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.5ms) Completed 200 OK in 74ms (Views: 71.7ms | ActiveRecord: 0.2ms) Started GET "/assets/items.self-4876d56a4eec8584619d6908341c8bdd1b0b79e7154842816c9e4bcc3a47b0e0.js?body=1" for ::1 at 2018-03-17 19:17:04 +0900 Started GET "/assets/sequenced_items.self-2ba42af2be59a6571f66dd014ed16f1d963e37dbda043f1de2ab2cfd411c25a4.js?body=1" for ::1 at 2018-03-17 19:17:04 +0900 Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:17:20 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["10", "3", "4", "1", "5", "6", "2", "7", "8", "9"]}  (0.0ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (10, 3, 4, 1, 5, 6, 2, 7, 8, 9) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:17:20.792157"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:17:20.793084"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:17:20.794359"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:17:20.795295"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:17:20.796011"], ["id", 5]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:17:20.796588"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:17:20.797117"], ["id", 2]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:17:20.797646"], ["id", 7]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:17:20.798177"], ["id", 8]] SQL (0.0ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:17:20.798697"], ["id", 9]]  (2.1ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 3.1ms) Started GET "/sequenced_items" for ::1 at 2018-03-17 19:17:58 +0900 Processing by SequencedItemsController#index as HTML Rendering sequenced_items/index.html.erb within layouts/application SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" ORDER BY "sequenced_items"."sequence" ASC Rendered sequenced_items/index.html.erb within layouts/application (9.9ms) Completed 200 OK in 42ms (Views: 37.8ms | ActiveRecord: 0.6ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:18:06 +0900 Processing by SortableController#reorder as */* Parameters: {"SequencedItem"=>["1", "10", "2", "3", "4", "5", "6", "7", "8", "9"]}  (0.1ms) begin transaction SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" WHERE "sequenced_items"."id" IN (1, 10, 2, 3, 4, 5, 6, 7, 8, 9) ORDER BY "sequenced_items"."sequence" ASC SQL (0.2ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 1], ["id", 10]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 2], ["id", 2]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 3], ["id", 3]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 4], ["id", 4]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 5], ["id", 5]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 6], ["id", 6]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 7], ["id", 7]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 8], ["id", 8]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 9], ["id", 9]]  (1.3ms) commit transaction Completed 200 OK in 9ms (ActiveRecord: 2.2ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:18:11 +0900 Processing by SortableController#reorder as */* Parameters: {"SequencedItem"=>["10", "2", "3", "4", "5", "6", "1", "7", "8", "9"]}  (0.0ms) begin transaction SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" WHERE "sequenced_items"."id" IN (10, 2, 3, 4, 5, 6, 1, 7, 8, 9) ORDER BY "sequenced_items"."sequence" ASC SQL (0.3ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 0], ["id", 10]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 1], ["id", 2]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 2], ["id", 3]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 3], ["id", 4]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 4], ["id", 5]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 5], ["id", 6]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 6], ["id", 1]]  (2.0ms) commit transaction Completed 200 OK in 9ms (ActiveRecord: 2.9ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:18:11 +0900 Processing by SortableController#reorder as */* Parameters: {"SequencedItem"=>["10", "2", "8", "3", "4", "5", "6", "1", "7", "9"]}  (0.1ms) begin transaction SequencedItem Load (0.3ms) SELECT "sequenced_items".* FROM "sequenced_items" WHERE "sequenced_items"."id" IN (10, 2, 8, 3, 4, 5, 6, 1, 7, 9) ORDER BY "sequenced_items"."sequence" ASC SQL (0.3ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 2], ["id", 8]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 3], ["id", 3]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 4], ["id", 4]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 5], ["id", 5]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 6], ["id", 6]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 7], ["id", 1]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 8], ["id", 7]]  (0.8ms) commit transaction Completed 200 OK in 10ms (ActiveRecord: 1.9ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:18:16 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["10", "3", "4", "1", "2", "5", "6", "7", "8", "9"]}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (10, 3, 4, 1, 2, 5, 6, 7, 8, 9) ORDER BY "items"."sort" ASC SQL (0.7ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:18:16.470579"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:18:16.472524"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:18:16.473615"], ["id", 6]]  (0.8ms) commit transaction Completed 200 OK in 7ms (ActiveRecord: 2.1ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:18:19 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["10", "6", "3", "4", "1", "2", "5", "7", "8", "9"]}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (10, 6, 3, 4, 1, 2, 5, 7, 8, 9) ORDER BY "items"."sort" ASC SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:18:19.528446"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:18:19.529833"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:18:19.530873"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:18:19.531836"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:18:19.533013"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:18:19.534034"], ["id", 5]]  (1.1ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 2.4ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:18:21 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["10", "6", "8", "3", "4", "1", "2", "5", "7", "9"]}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (10, 6, 8, 3, 4, 1, 2, 5, 7, 9) ORDER BY "items"."sort" ASC SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:18:21.466200"], ["id", 8]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:18:21.467459"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:18:21.469185"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:18:21.470458"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:18:21.471583"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:18:21.473373"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:18:21.474581"], ["id", 7]]  (1.9ms) commit transaction Completed 200 OK in 13ms (ActiveRecord: 3.3ms) Started GET "/items" for ::1 at 2018-03-17 19:21:13 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (4.2ms) Completed 200 OK in 29ms (Views: 26.7ms | ActiveRecord: 0.2ms) Started GET "/items" for ::1 at 2018-03-17 19:21:22 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (3.3ms) Completed 200 OK in 24ms (Views: 21.6ms | ActiveRecord: 0.2ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:21:27 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["9", "10", "6", "8", "3", "4", "1", "2", "5", "7"]}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (9, 10, 6, 8, 3, 4, 1, 2, 5, 7) ORDER BY "items"."sort" ASC SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-03-17 10:21:27.296657"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:21:27.297997"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:21:27.299198"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-03-17 10:21:27.300287"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-03-17 10:21:27.301100"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:21:27.301746"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:21:27.302335"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:21:27.302924"], ["id", 2]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-03-17 10:21:27.303813"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-03-17 10:21:27.304985"], ["id", 7]]  (1.2ms) commit transaction Completed 200 OK in 13ms (ActiveRecord: 2.6ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:21:29 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["9", "6", "10", "8", "3", "4", "1", "2", "5", "7"]}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (9, 6, 10, 8, 3, 4, 1, 2, 5, 7) ORDER BY "items"."sort" ASC SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-03-17 10:21:29.800684"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-03-17 10:21:29.806255"], ["id", 10]]  (1.6ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 2.3ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:21:30 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["9", "6", "10", "8", "3", "2", "4", "1", "5", "7"]}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (9, 6, 10, 8, 3, 2, 4, 1, 5, 7) ORDER BY "items"."sort" ASC SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-03-17 10:21:30.703365"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-03-17 10:21:30.704350"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-03-17 10:21:30.705109"], ["id", 1]]  (2.4ms) commit transaction Completed 200 OK in 8ms (ActiveRecord: 3.1ms) Started GET "/sequenced_items" for ::1 at 2018-03-17 19:21:35 +0900 Processing by SequencedItemsController#index as HTML Rendering sequenced_items/index.html.erb within layouts/application SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" ORDER BY "sequenced_items"."sequence" ASC Rendered sequenced_items/index.html.erb within layouts/application (2.8ms) Completed 200 OK in 26ms (Views: 23.1ms | ActiveRecord: 0.2ms) Started GET "/sequenced_items" for ::1 at 2018-03-17 19:21:42 +0900 Processing by SequencedItemsController#index as HTML Rendering sequenced_items/index.html.erb within layouts/application SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" ORDER BY "sequenced_items"."sequence" ASC Rendered sequenced_items/index.html.erb within layouts/application (2.6ms) Completed 200 OK in 27ms (Views: 24.1ms | ActiveRecord: 0.2ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:21:45 +0900 Processing by SortableController#reorder as */* Parameters: {"SequencedItem"=>["10", "2", "7", "8", "3", "4", "5", "6", "1", "9"]}  (0.0ms) begin transaction SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" WHERE "sequenced_items"."id" IN (10, 2, 7, 8, 3, 4, 5, 6, 1, 9) ORDER BY "sequenced_items"."sequence" ASC SQL (0.3ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 2], ["id", 7]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 3], ["id", 8]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 4], ["id", 3]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 5], ["id", 4]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 6], ["id", 5]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 7], ["id", 6]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 8], ["id", 1]]  (2.4ms) commit transaction Completed 200 OK in 10ms (ActiveRecord: 3.4ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:21:46 +0900 Processing by SortableController#reorder as */* Parameters: {"SequencedItem"=>["10", "6", "2", "7", "8", "3", "4", "5", "1", "9"]}  (0.1ms) begin transaction SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" WHERE "sequenced_items"."id" IN (10, 6, 2, 7, 8, 3, 4, 5, 1, 9) ORDER BY "sequenced_items"."sequence" ASC SQL (0.3ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 1], ["id", 6]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 2], ["id", 2]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 3], ["id", 7]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 4], ["id", 8]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 5], ["id", 3]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 6], ["id", 4]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 7], ["id", 5]]  (0.5ms) commit transaction Completed 200 OK in 8ms (ActiveRecord: 1.6ms) Started POST "/sortable/reorder" for ::1 at 2018-03-17 19:21:46 +0900 Processing by SortableController#reorder as */* Parameters: {"SequencedItem"=>["10", "5", "6", "2", "7", "8", "3", "4", "1", "9"]}  (0.1ms) begin transaction SequencedItem Load (0.2ms) SELECT "sequenced_items".* FROM "sequenced_items" WHERE "sequenced_items"."id" IN (10, 5, 6, 2, 7, 8, 3, 4, 1, 9) ORDER BY "sequenced_items"."sequence" ASC SQL (0.2ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 1], ["id", 5]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 2], ["id", 6]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 3], ["id", 2]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 4], ["id", 7]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 5], ["id", 8]] SQL (0.0ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 6], ["id", 3]] SQL (0.1ms) UPDATE "sequenced_items" SET "sequence" = ? WHERE "sequenced_items"."id" = ? [["sequence", 7], ["id", 4]]  (2.0ms) commit transaction Completed 200 OK in 9ms (ActiveRecord: 2.8ms) Started GET "/" for ::1 at 2018-04-27 14:23:06 +0900  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Processing by Rails::WelcomeController#index as HTML Rendering /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.5.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb Rendered /Users/madmax/Programs/itmammoth/rails_sortable/vendor/bundle/ruby/2.5.0/gems/railties-5.1.4/lib/rails/templates/rails/welcome/index.html.erb (3.0ms) Completed 200 OK in 18ms (Views: 9.9ms | ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2018-04-27 14:23:11 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.3ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (13.0ms) Completed 200 OK in 321ms (Views: 316.6ms | ActiveRecord: 1.0ms) Started GET "/assets/application.self-f9e7c1541e1b8783561468c59162bd896007380f5a3799ef2169d3a3fdf40bed.css?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/scaffold.self-83b741db49389dc7cfdf85bf7537a0219cce48e085c4116afc83d55c9af47c78.css?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery.self-bd7ddd393353a8d2480a622e80342adf488fb6006d667e8b42e4c0073393abee.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery_ujs.self-784a997f6726036b1993eb2217c9cb558e1cbb801c6da88105588c56f13b466a.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery-ui/version.self-c8e3d1203da26ea7efdf83c1eabb3f0ba55cb68e463f5ccf0d77bd15ce6a8e61.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery-ui/ie.self-163eb039dcb1e0c3471b7d91386eabb8753da6906e84824c019f9ff56bdb83a6.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery-ui/widget.self-fca20bcec06d192f97cffa6e734e24360e227237b8ae7d7e7e60754df7d5444f.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery-ui/widgets/mouse.self-f0790a202dcf54fab051e71b042335d5346f9cceb0f645223c1dab93f349a04b.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery-ui/data.self-2067c82e6fe39a9cba5623206a5dc28d5c60d26b95cd100855b78cacf72865b8.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery-ui/scroll-parent.self-38e26a71a450a29fb63425e8f589a7046862f927eb0a1dbf6a6c44da05196d2e.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/jquery-ui/widgets/sortable.self-6e5e1f07a29b5a89ec1d359363d2800904e3ae548a38ab2f8eded36746d1adbe.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/items.self-4876d56a4eec8584619d6908341c8bdd1b0b79e7154842816c9e4bcc3a47b0e0.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/application.self-120f3cf1cb67bea53c4a2a57336b34ca04b04b55a55c38cf76ce20a147e5eff9.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/sequenced_items.self-2ba42af2be59a6571f66dd014ed16f1d963e37dbda043f1de2ab2cfd411c25a4.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/rails_sortable.self-e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started GET "/assets/plugin.self-71865420b247761874b05b5da71965c61cfab1ebc2f7f4b297d85415b05e8355.js?body=1" for ::1 at 2018-04-27 14:23:11 +0900 Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:23:19 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `map' for #): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:23:in `parse_params' /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:6:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:26:38 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (15.0ms) Completed 200 OK in 72ms (Views: 64.7ms | ActiveRecord: 1.0ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:26:43 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `keys' for #): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:22:in `parse_params' /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:6:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:27:08 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (12.6ms) Completed 200 OK in 42ms (Views: 38.1ms | ActiveRecord: 1.0ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:27:12 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7", "controller"=>"sortable", "action"=>"reorder"} Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `keys' for #): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:23:in `parse_params' /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:6:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:28:03 +0900  (0.4ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (9.3ms) Completed 200 OK in 226ms (Views: 221.1ms | ActiveRecord: 0.6ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:28:07 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7", "controller"=>"sortable", "action"=>"reorder"} Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `keys' for #): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:23:in `parse_params' /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:6:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:28:56 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (11.7ms) Completed 200 OK in 38ms (Views: 34.5ms | ActiveRecord: 0.9ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:29:00 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7", "controller"=>"sortable", "action"=>"reorder"} Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `keys' for #): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:23:in `parse_params' /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:6:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:29:23 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (14.3ms) Completed 200 OK in 56ms (Views: 44.7ms | ActiveRecord: 1.2ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:29:27 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7 Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `sort_attribute' for true:TrueClass): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:7:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:31:03 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (1.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (13.1ms) Completed 200 OK in 53ms (Views: 49.2ms | ActiveRecord: 2.7ms) Started GET "/assets/plugin.self-7a7940dd15ae98b294e8ed7a98aacaefdeeb2e759677094bcca792cd40f4d826.js?body=1" for ::1 at 2018-04-27 14:31:03 +0900 Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:31:06 +0900 Processing by SortableController#reorder as */* Parameters: {"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]} {"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"], "controller"=>"sortable", "action"=>"reorder"} Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `sort_attribute' for true:TrueClass): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:7:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:36:22 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (5.6ms) Completed 200 OK in 34ms (Views: 32.3ms | ActiveRecord: 0.2ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:36:25 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7", "controller"=>"sortable", "action"=>"reorder"} Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) NoMethodError (undefined method `sort_attribute' for true:TrueClass): /Users/madmax/Programs/itmammoth/rails_sortable/app/controllers/sortable_controller.rb:7:in `reorder' actionpack (5.1.4) lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' actionpack (5.1.4) lib/abstract_controller/base.rb:186:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rendering.rb:30:in `process_action' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:20:in `block in process_action' activesupport (5.1.4) lib/active_support/callbacks.rb:131:in `run_callbacks' actionpack (5.1.4) lib/abstract_controller/callbacks.rb:19:in `process_action' actionpack (5.1.4) lib/action_controller/metal/rescue.rb:20:in `process_action' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `block in instrument' activesupport (5.1.4) lib/active_support/notifications/instrumenter.rb:21:in `instrument' activesupport (5.1.4) lib/active_support/notifications.rb:166:in `instrument' actionpack (5.1.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (5.1.4) lib/action_controller/metal/params_wrapper.rb:252:in `process_action' activerecord (5.1.4) lib/active_record/railties/controller_runtime.rb:22:in `process_action' actionpack (5.1.4) lib/abstract_controller/base.rb:124:in `process' actionview (5.1.4) lib/action_view/rendering.rb:30:in `process' actionpack (5.1.4) lib/action_controller/metal.rb:189:in `dispatch' actionpack (5.1.4) lib/action_controller/metal.rb:253:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:49:in `dispatch' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:31:in `serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:50:in `block in serve' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `each' actionpack (5.1.4) lib/action_dispatch/journey/router.rb:33:in `serve' actionpack (5.1.4) lib/action_dispatch/routing/route_set.rb:834:in `call' rack (2.0.3) lib/rack/etag.rb:25:in `call' rack (2.0.3) lib/rack/conditional_get.rb:38:in `call' rack (2.0.3) lib/rack/head.rb:12:in `call' rack (2.0.3) lib/rack/session/abstract/id.rb:232:in `context' rack (2.0.3) lib/rack/session/abstract/id.rb:226:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/cookies.rb:613:in `call' activerecord (5.1.4) lib/active_record/migration.rb:556:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' activesupport (5.1.4) lib/active_support/callbacks.rb:97:in `run_callbacks' actionpack (5.1.4) lib/action_dispatch/middleware/callbacks.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' railties (5.1.4) lib/rails/rack/logger.rb:36:in `call_app' railties (5.1.4) lib/rails/rack/logger.rb:24:in `block in call' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `block in tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (5.1.4) lib/active_support/tagged_logging.rb:69:in `tagged' railties (5.1.4) lib/rails/rack/logger.rb:24:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/remote_ip.rb:79:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/request_id.rb:25:in `call' rack (2.0.3) lib/rack/method_override.rb:22:in `call' rack (2.0.3) lib/rack/runtime.rb:22:in `call' activesupport (5.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/executor.rb:12:in `call' actionpack (5.1.4) lib/action_dispatch/middleware/static.rb:125:in `call' rack (2.0.3) lib/rack/sendfile.rb:111:in `call' railties (5.1.4) lib/rails/engine.rb:522:in `call' rack (2.0.3) lib/rack/handler/webrick.rb:86:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:140:in `service' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/httpserver.rb:96:in `run' /Users/madmax/.rbenv/versions/2.5.0/lib/ruby/2.5.0/webrick/server.rb:307:in `block in start_thread' Started GET "/items" for ::1 at 2018-04-27 14:36:41 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (11.3ms) Completed 200 OK in 41ms (Views: 37.2ms | ActiveRecord: 1.1ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:36:44 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7"} {"railsSortable"=>"Item[]=1&Item[]=9&Item[]=6&Item[]=10&Item[]=8&Item[]=3&Item[]=2&Item[]=4&Item[]=5&Item[]=7", "controller"=>"sortable", "action"=>"reorder"} Completed 200 OK in 1ms (ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2018-04-27 14:48:37 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (3.5ms) Completed 200 OK in 83ms (Views: 80.4ms | ActiveRecord: 0.2ms) Started GET "/assets/plugin.self-e21d658d2626d06895495bdb379366bdbf64b4e5d9e66f659d597138b95249e6.js?body=1" for ::1 at 2018-04-27 14:48:37 +0900 Started GET "/items" for ::1 at 2018-04-27 14:54:34 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (4.7ms) Completed 200 OK in 85ms (Views: 83.3ms | ActiveRecord: 0.2ms) Started GET "/assets/plugin.self-ad8ab11a1a465677583a89ab029cdb1a00682c23cfe8b8eb540b6f79c1bd3092.js?body=1" for ::1 at 2018-04-27 14:54:34 +0900 Started GET "/items" for ::1 at 2018-04-27 14:56:12 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (10.5ms) Completed 200 OK in 126ms (Views: 113.5ms | ActiveRecord: 0.7ms) Started GET "/assets/plugin.self-dc7dae3efa5ee3908a8449e2893fe2e5861cd4ed1f7412a0dd1a12669a4f54fa.js?body=1" for ::1 at 2018-04-27 14:56:12 +0900 Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:56:15 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>{"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]}} {"railsSortable"=>{"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]}, "controller"=>"sortable", "action"=>"reorder"} Completed 200 OK in 1ms (ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2018-04-27 14:57:06 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (13.1ms) Completed 200 OK in 43ms (Views: 39.2ms | ActiveRecord: 1.0ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:57:09 +0900 Processing by SortableController#reorder as */* Parameters: {"railsSortable"=>{"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]}} {"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]} Completed 200 OK in 1ms (ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2018-04-27 14:59:10 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (12.1ms) Completed 200 OK in 87ms (Views: 83.7ms | ActiveRecord: 1.2ms) Started GET "/assets/plugin.self-d182d4bf24ec1db6e02e1b0dba90b6a070751ec557496c179a5d68b2ce321083.js?body=1" for ::1 at 2018-04-27 14:59:11 +0900 Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:59:13 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]}} {"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]} Completed 200 OK in 1ms (ActiveRecord: 0.0ms) Started GET "/items" for ::1 at 2018-04-27 14:59:42 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.1ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (12.5ms) Completed 200 OK in 41ms (Views: 37.9ms | ActiveRecord: 1.0ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:59:47 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "9", "6", "10", "8", "3", "2", "4", "5", "7"]}}  (0.1ms) begin transaction Item Load (0.4ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 9, 6, 10, 8, 3, 2, 4, 5, 7) ORDER BY "items"."sort" ASC SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 0], ["updated_at", "2018-04-27 05:59:47.954814"], ["id", 1]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-04-27 05:59:47.956553"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-04-27 05:59:47.957516"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-04-27 05:59:47.958508"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-04-27 05:59:47.959938"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-04-27 05:59:47.961435"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-04-27 05:59:47.962880"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-04-27 05:59:47.963878"], ["id", 4]]  (2.9ms) commit transaction Completed 200 OK in 17ms (ActiveRecord: 4.6ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:59:54 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "2", "9", "6", "10", "8", "3", "4", "5", "7"]}}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 9, 6, 10, 8, 3, 4, 5, 7) ORDER BY "items"."sort" ASC SQL (0.8ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 1], ["updated_at", "2018-04-27 05:59:54.787122"], ["id", 2]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-04-27 05:59:54.789717"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-04-27 05:59:54.791298"], ["id", 6]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-04-27 05:59:54.792591"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-04-27 05:59:54.793815"], ["id", 8]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-04-27 05:59:54.795351"], ["id", 3]]  (2.5ms) commit transaction Completed 200 OK in 15ms (ActiveRecord: 4.4ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:59:57 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "2", "3", "9", "6", "10", "8", "4", "5", "7"]}}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 9, 6, 10, 8, 4, 5, 7) ORDER BY "items"."sort" ASC SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 2], ["updated_at", "2018-04-27 05:59:57.049884"], ["id", 3]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-04-27 05:59:57.051231"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-04-27 05:59:57.052190"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-04-27 05:59:57.053777"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-04-27 05:59:57.055223"], ["id", 8]]  (2.6ms) commit transaction Completed 200 OK in 12ms (ActiveRecord: 3.7ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 14:59:59 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "2", "3", "4", "9", "6", "10", "8", "5", "7"]}}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 9, 6, 10, 8, 5, 7) ORDER BY "items"."sort" ASC SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 3], ["updated_at", "2018-04-27 05:59:59.140786"], ["id", 4]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-04-27 05:59:59.142413"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-04-27 05:59:59.143736"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-04-27 05:59:59.144974"], ["id", 10]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-04-27 05:59:59.146139"], ["id", 8]]  (2.2ms) commit transaction Completed 200 OK in 12ms (ActiveRecord: 3.4ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 15:00:01 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "2", "3", "4", "5", "9", "6", "10", "8", "7"]}}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 9, 6, 10, 8, 7) ORDER BY "items"."sort" ASC SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 4], ["updated_at", "2018-04-27 06:00:01.223297"], ["id", 5]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-04-27 06:00:01.224808"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-04-27 06:00:01.226090"], ["id", 6]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-04-27 06:00:01.227473"], ["id", 10]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-04-27 06:00:01.228876"], ["id", 8]]  (2.3ms) commit transaction Completed 200 OK in 12ms (ActiveRecord: 3.6ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 15:00:02 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "2", "3", "4", "5", "6", "9", "10", "8", "7"]}}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 6, 9, 10, 8, 7) ORDER BY "items"."sort" ASC SQL (0.3ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 5], ["updated_at", "2018-04-27 06:00:02.786231"], ["id", 6]] SQL (0.2ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-04-27 06:00:02.787800"], ["id", 9]]  (2.0ms) commit transaction Completed 200 OK in 8ms (ActiveRecord: 2.8ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 15:00:05 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "2", "3", "4", "5", "6", "7", "9", "10", "8"]}}  (0.1ms) begin transaction Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 6, 7, 9, 10, 8) ORDER BY "items"."sort" ASC SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 6], ["updated_at", "2018-04-27 06:00:05.521609"], ["id", 7]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-04-27 06:00:05.524057"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-04-27 06:00:05.526657"], ["id", 10]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-04-27 06:00:05.529503"], ["id", 8]]  (2.1ms) commit transaction Completed 200 OK in 14ms (ActiveRecord: 3.3ms) Started POST "/sortable/reorder" for ::1 at 2018-04-27 15:00:07 +0900 Processing by SortableController#reorder as */* Parameters: {"rails_sortable"=>{"Item"=>["1", "2", "3", "4", "5", "6", "7", "8", "9", "10"]}}  (0.1ms) begin transaction Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10) ORDER BY "items"."sort" ASC SQL (0.4ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 7], ["updated_at", "2018-04-27 06:00:07.542380"], ["id", 8]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 8], ["updated_at", "2018-04-27 06:00:07.543767"], ["id", 9]] SQL (0.1ms) UPDATE "items" SET "sort" = ?, "updated_at" = ? WHERE "items"."id" = ? [["sort", 9], ["updated_at", "2018-04-27 06:00:07.544834"], ["id", 10]]  (2.0ms) commit transaction Completed 200 OK in 11ms (ActiveRecord: 2.9ms) Started GET "/items" for ::1 at 2018-04-27 15:00:09 +0900 Processing by ItemsController#index as HTML Rendering items/index.html.erb within layouts/application Item Load (0.2ms) SELECT "items".* FROM "items" ORDER BY "items"."sort" ASC Rendered items/index.html.erb within layouts/application (2.6ms) Completed 200 OK in 32ms (Views: 28.8ms | ActiveRecord: 0.2ms)