Started GET "/" for 172.18.0.1 at 2020-02-11 02:12:28 +0000
  
Mysql2::Error::ConnectionError (Can't connect to local MySQL server through socket '/var/lib/mysql/mysql.sock' (2)):
  
mysql2 (0.5.3) lib/mysql2/client.rb:90:in `connect'
mysql2 (0.5.3) lib/mysql2/client.rb:90:in `initialize'
activerecord (6.0.2.1) lib/active_record/connection_adapters/mysql2_adapter.rb:24:in `new'
activerecord (6.0.2.1) lib/active_record/connection_adapters/mysql2_adapter.rb:24:in `mysql2_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:889:in `new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:933:in `checkout_new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:912:in `try_to_checkout_new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:873:in `acquire_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:595:in `checkout'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:439:in `connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:1121:in `retrieve_connection'
activerecord (6.0.2.1) lib/active_record/connection_handling.rb:238:in `retrieve_connection'
activerecord (6.0.2.1) lib/active_record/connection_handling.rb:206:in `connection'
activerecord (6.0.2.1) lib/active_record/migration.rb:562:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'
activesupport (6.0.2.1) lib/active_support/callbacks.rb:101:in `run_callbacks'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/actionable_exceptions.rb:17:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:32:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/favicon.ico" for 172.18.0.1 at 2020-02-11 02:12:28 +0000
  
Mysql2::Error::ConnectionError (Can't connect to local MySQL server through socket '/var/lib/mysql/mysql.sock' (2)):
  
mysql2 (0.5.3) lib/mysql2/client.rb:90:in `connect'
mysql2 (0.5.3) lib/mysql2/client.rb:90:in `initialize'
activerecord (6.0.2.1) lib/active_record/connection_adapters/mysql2_adapter.rb:24:in `new'
activerecord (6.0.2.1) lib/active_record/connection_adapters/mysql2_adapter.rb:24:in `mysql2_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:889:in `new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:933:in `checkout_new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:912:in `try_to_checkout_new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:873:in `acquire_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:595:in `checkout'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:439:in `connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:1121:in `retrieve_connection'
activerecord (6.0.2.1) lib/active_record/connection_handling.rb:238:in `retrieve_connection'
activerecord (6.0.2.1) lib/active_record/connection_handling.rb:206:in `connection'
activerecord (6.0.2.1) lib/active_record/migration.rb:562:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'
activesupport (6.0.2.1) lib/active_support/callbacks.rb:101:in `run_callbacks'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/actionable_exceptions.rb:17:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:32:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/" for 172.18.0.1 at 2020-02-11 02:13:17 +0000
  
ActiveRecord::NoDatabaseError (Unknown database 'app_development'):
  
activerecord (6.0.2.1) lib/active_record/connection_adapters/mysql2_adapter.rb:28:in `rescue in mysql2_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/mysql2_adapter.rb:14:in `mysql2_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:889:in `new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:933:in `checkout_new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:912:in `try_to_checkout_new_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:873:in `acquire_connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:595:in `checkout'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:439:in `connection'
activerecord (6.0.2.1) lib/active_record/connection_adapters/abstract/connection_pool.rb:1121:in `retrieve_connection'
activerecord (6.0.2.1) lib/active_record/connection_handling.rb:238:in `retrieve_connection'
activerecord (6.0.2.1) lib/active_record/connection_handling.rb:206:in `connection'
activerecord (6.0.2.1) lib/active_record/migration.rb:562:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'
activesupport (6.0.2.1) lib/active_support/callbacks.rb:101:in `run_callbacks'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/actionable_exceptions.rb:17:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:32:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (1.0ms)[0m  [1m[35mCREATE DATABASE `app_development` DEFAULT CHARACTER SET `utf8mb4`[0m
  [1m[35m (0.3ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.4ms)[0m  [1m[35mCREATE DATABASE `app_test` DEFAULT CHARACTER SET `utf8mb4`[0m
Started GET "/" for 172.18.0.1 at 2020-02-11 02:17:20 +0000
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by Rails::WelcomeController#index as HTML
  Rendering vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/welcome/index.html.erb
  Rendered vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/welcome/index.html.erb (Duration: 4.7ms | Allocations: 311)
Completed 200 OK in 8ms (Views: 6.4ms | ActiveRecord: 0.0ms | Allocations: 1783)


  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.4ms)[0m  [1m[35mCREATE DATABASE `app_development` DEFAULT CHARACTER SET `utf8mb4`[0m
  [1m[35m (0.3ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[35mCREATE DATABASE `app_test` DEFAULT CHARACTER SET `utf8mb4`[0m
Started GET "/" for 172.18.0.1 at 2020-02-11 02:22:00 +0000
Processing by Rails::WelcomeController#index as HTML
  Rendering vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/welcome/index.html.erb
  Rendered vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/welcome/index.html.erb (Duration: 4.4ms | Allocations: 194)
Completed 200 OK in 6ms (Views: 5.3ms | ActiveRecord: 0.0ms | Allocations: 923)


Started GET "/rails/info" for 172.18.0.1 at 2020-02-11 02:22:07 +0000
Processing by Rails::InfoController#index as HTML
Redirected to http://localhost:3000/rails/info/routes
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 603)


Started GET "/rails/info/routes" for 172.18.0.1 at 2020-02-11 02:22:07 +0000
Processing by Rails::InfoController#routes as HTML
  Rendering vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/info/routes.html.erb within layouts/application
  Rendered collection of vendor/bundle/ruby/2.6.0/gems/actionpack-6.0.2.1/lib/action_dispatch/middleware/templates/routes/_route.html.erb [23 times] (Duration: 6.0ms | Allocations: 3356)
  Rendered vendor/bundle/ruby/2.6.0/gems/actionpack-6.0.2.1/lib/action_dispatch/middleware/templates/routes/_table.html.erb (Duration: 2.9ms | Allocations: 1192)
  Rendered vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/info/routes.html.erb within layouts/application (Duration: 11.3ms | Allocations: 6297)
Completed 200 OK in 14ms (Views: 13.0ms | ActiveRecord: 0.0ms | Allocations: 7454)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 02:22:22 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 215)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:17:54 +0000
  [1m[35m (0.7ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 225)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:18:54 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 146)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:19:00 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 146)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:20:12 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 149)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:24:51 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 149)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:24:52 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.8 at 2020-02-11 03:36:17 +0000
  [1m[35m (0.7ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by PostsController#index as */*
Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 227)


Started GET "/posts" for 172.18.0.8 at 2020-02-11 03:36:56 +0000
Processing by PostsController#index as */*
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 148)


Started GET "/" for 172.18.0.7 at 2020-02-11 03:38:29 +0000
Processing by Rails::WelcomeController#index as */*
  Rendering vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/welcome/index.html.erb
  Rendered vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/welcome/index.html.erb (Duration: 7.2ms | Allocations: 311)
Completed 200 OK in 14ms (Views: 10.2ms | ActiveRecord: 0.0ms | Allocations: 1659)


Started GET "/posts" for 172.18.0.7 at 2020-02-11 03:38:32 +0000
Processing by PostsController#index as */*
Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 148)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:41:43 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 147)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:50:28 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:50:29 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:58:28 +0000
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by PostsController#index as */*
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 146)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:58:47 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 03:58:49 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:58:53 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 149)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 03:59:07 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:00:26 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:00:44 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:00:59 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 04:02:44 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:06:28 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:07:12 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:12:44 +0000
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:12:47 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:13:31 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:18:27 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:19:27 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:19:33 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:19:42 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:20:53 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:22:57 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:24:14 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.3ms | ActiveRecord: 0.0ms | Allocations: 144)


Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 04:27:25 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 04:28:07 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 04:28:15 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 04:30:07 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 04:30:14 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:31:19 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:32:15 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 144)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:35:20 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms | Allocations: 223)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:35:25 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 146)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 04:35:26 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 146)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 06:18:08 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 18ms (Views: 17.5ms | ActiveRecord: 0.0ms | Allocations: 146)


  [1m[35m (0.7ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.7ms)[0m  [1m[35mCREATE DATABASE `app_development` DEFAULT CHARACTER SET `utf8mb4`[0m
  [1m[35m (0.3ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[35mCREATE DATABASE `app_test` DEFAULT CHARACTER SET `utf8mb4`[0m
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  ↳ (erb):38:in `migration_template'
Started GET "/rails/info" for 172.18.0.1 at 2020-02-11 06:47:02 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  
ActiveRecord::PendingMigrationError (

Migrations are pending. To resolve this issue, run:

        rails db:migrate RAILS_ENV=development

):
  
activerecord (6.0.2.1) lib/active_record/migration.rb:587:in `check_pending!'
activerecord (6.0.2.1) lib/active_record/migration.rb:564:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'
activesupport (6.0.2.1) lib/active_support/callbacks.rb:101:in `run_callbacks'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/actionable_exceptions.rb:17:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:32:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
  [1m[35m (0.8ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (14.1ms)[0m  [1m[35mCREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL PRIMARY KEY)[0m
  [1m[35m (4.2ms)[0m  [1m[35mCREATE TABLE `ar_internal_metadata` (`key` varchar(255) NOT NULL PRIMARY KEY, `value` varchar(255), `created_at` datetime(6) NOT NULL, `updated_at` datetime(6) NOT NULL)[0m
  [1m[35m (0.2ms)[0m  [1m[34mSELECT GET_LOCK('5277929974492897470', 0)[0m
  [1m[35m (0.4ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Migrating to DeviseTokenAuthCreateUsers (20200211063250)
  [1m[35m (3.9ms)[0m  [1m[35mCREATE TABLE `users` (`id` bigint NOT NULL AUTO_INCREMENT PRIMARY KEY, `provider` varchar(255) DEFAULT 'email' NOT NULL, `uid` varchar(255) DEFAULT '' NOT NULL, `encrypted_password` varchar(255) DEFAULT '' NOT NULL, `reset_password_token` varchar(255), `reset_password_sent_at` datetime, `allow_password_change` tinyint(1) DEFAULT FALSE, `remember_created_at` datetime, `confirmation_token` varchar(255), `confirmed_at` datetime, `confirmation_sent_at` datetime, `unconfirmed_email` varchar(255), `name` varchar(255), `nickname` varchar(255), `image` varchar(255), `email` varchar(255), `tokens` text, `created_at` datetime(6) NOT NULL, `updated_at` datetime(6) NOT NULL)[0m
  [1m[35m (6.0ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_email`  ON `users` (`email`) [0m
  [1m[35m (3.3ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_uid_and_provider`  ON `users` (`uid`, `provider`) [0m
  [1m[35m (3.2ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_reset_password_token`  ON `users` (`reset_password_token`) [0m
  [1m[35m (3.9ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_confirmation_token`  ON `users` (`confirmation_token`) [0m
  [1m[35m (0.4ms)[0m  [1m[35mBEGIN[0m
  [1m[36mprimary::SchemaMigration Create (0.6ms)[0m  [1m[32mINSERT INTO `schema_migrations` (`version`) VALUES ('20200211063250')[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mActiveRecord::InternalMetadata Load (0.4ms)[0m  [1m[34mSELECT `ar_internal_metadata`.* FROM `ar_internal_metadata` WHERE `ar_internal_metadata`.`key` = 'environment' LIMIT 1[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mActiveRecord::InternalMetadata Create (0.3ms)[0m  [1m[32mINSERT INTO `ar_internal_metadata` (`key`, `value`, `created_at`, `updated_at`) VALUES ('environment', 'development', '2020-02-11 06:47:17.527356', '2020-02-11 06:47:17.527356')[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[34mSELECT RELEASE_LOCK('5277929974492897470')[0m
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Started GET "/rails/info" for 172.18.0.1 at 2020-02-11 06:47:20 +0000
  [1m[35m (0.4ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Processing by Rails::InfoController#index as HTML
Redirected to http://localhost:3000/rails/info/routes
Completed 302 Found in 2ms (ActiveRecord: 0.0ms | Allocations: 1236)


Started GET "/rails/info/routes" for 172.18.0.1 at 2020-02-11 06:47:20 +0000
Processing by Rails::InfoController#routes as HTML
  Rendering vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/info/routes.html.erb within layouts/application
  Rendered collection of vendor/bundle/ruby/2.6.0/gems/actionpack-6.0.2.1/lib/action_dispatch/middleware/templates/routes/_route.html.erb [39 times] (Duration: 13.4ms | Allocations: 5520)
  Rendered vendor/bundle/ruby/2.6.0/gems/actionpack-6.0.2.1/lib/action_dispatch/middleware/templates/routes/_table.html.erb (Duration: 2.1ms | Allocations: 915)
  Rendered vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/info/routes.html.erb within layouts/application (Duration: 18.7ms | Allocations: 8920)
Completed 200 OK in 22ms (Views: 20.6ms | ActiveRecord: 0.0ms | Allocations: 10117)


Started GET "/auth/sign_in" for 172.18.0.1 at 2020-02-11 06:47:45 +0000
Processing by DeviseTokenAuth::SessionsController#new as HTML
Completed 405 Method Not Allowed in 52ms (Views: 0.3ms | ActiveRecord: 0.0ms | Allocations: 18172)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 06:49:35 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 201)


Started OPTIONS "/posts" for 172.18.0.1 at 2020-02-11 06:51:07 +0000
  
ActionController::RoutingError (No route matches [OPTIONS] "/posts"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started POST "/auth/sing_up" for 172.18.0.1 at 2020-02-11 07:06:07 +0000
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  
ActionController::RoutingError (No route matches [POST] "/auth/sing_up"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started POST "/auth/sing_up" for 172.18.0.1 at 2020-02-11 07:07:56 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.5ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  
ActionController::RoutingError (No route matches [POST] "/auth/sing_up"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started POST "/auth/sing_up" for 172.18.0.1 at 2020-02-11 07:11:28 +0000
  
ActionController::RoutingError (No route matches [POST] "/auth/sing_up"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started POST "/auth/sing_up" for 172.18.0.1 at 2020-02-11 07:11:30 +0000
  
ActionController::RoutingError (No route matches [POST] "/auth/sing_up"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started POST "/auth/sing_up" for 172.18.0.1 at 2020-02-11 07:12:35 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.9ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  
ActionController::RoutingError (No route matches [POST] "/auth/sing_up"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
rack-cors (1.1.1) lib/rack/cors.rb:100:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/posts" for 172.18.0.1 at 2020-02-11 07:13:00 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 265)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 07:14:29 +0000
  
ArgumentError (Before process_action callback :verify_authenticity_token has not been defined):
  
app/controllers/application_controller.rb:3:in `<class:ApplicationController>'
app/controllers/application_controller.rb:1:in `<main>'
app/controllers/posts_controller.rb:1:in `<main>'
Started GET "/posts" for 172.18.0.1 at 2020-02-11 07:23:39 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 211)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 07:27:11 +0000
  [1m[35m (0.5ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms | Allocations: 269)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 07:27:13 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 2ms (Views: 1.9ms | ActiveRecord: 0.0ms | Allocations: 162)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 07:28:01 +0000
  [1m[35m (0.5ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 269)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 07:28:02 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 162)


Started POST "/auth/sing_up" for 172.18.0.1 at 2020-02-11 07:28:12 +0000
  
ActionController::RoutingError (No route matches [POST] "/auth/sing_up"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
rack-cors (1.1.1) lib/rack/cors.rb:100:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/rails/info" for 172.18.0.1 at 2020-02-11 07:28:30 +0000
Processing by Rails::InfoController#index as HTML
Redirected to http://localhost:3000/rails/info/routes
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 764)


Started GET "/rails/info/routes" for 172.18.0.1 at 2020-02-11 07:28:30 +0000
Processing by Rails::InfoController#routes as HTML
  Rendering vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/info/routes.html.erb within layouts/application
  Rendered collection of vendor/bundle/ruby/2.6.0/gems/actionpack-6.0.2.1/lib/action_dispatch/middleware/templates/routes/_route.html.erb [39 times] (Duration: 9.1ms | Allocations: 5491)
  Rendered vendor/bundle/ruby/2.6.0/gems/actionpack-6.0.2.1/lib/action_dispatch/middleware/templates/routes/_table.html.erb (Duration: 1.0ms | Allocations: 692)
  Rendered vendor/bundle/ruby/2.6.0/gems/railties-6.0.2.1/lib/rails/templates/rails/info/routes.html.erb within layouts/application (Duration: 13.1ms | Allocations: 8654)
Completed 200 OK in 16ms (Views: 14.8ms | ActiveRecord: 0.0ms | Allocations: 9850)


Started POST "/auth/sing_up" for 172.18.0.1 at 2020-02-11 07:28:37 +0000
  
ActionController::RoutingError (No route matches [POST] "/auth/sing_up"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
rack-cors (1.1.1) lib/rack/cors.rb:100:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:29:59 +0000
Processing by DeviseTokenAuth::RegistrationsController#create as HTML
Filter chain halted as :validate_sign_up_params rendered or redirected
Completed 422 Unprocessable Entity in 11ms (Views: 0.3ms | ActiveRecord: 0.0ms | Allocations: 6546)


Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:30:35 +0000
Processing by DeviseTokenAuth::RegistrationsController#create as HTML
Filter chain halted as :validate_sign_up_params rendered or redirected
Completed 422 Unprocessable Entity in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 254)


Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:34:35 +0000
Processing by DeviseTokenAuth::RegistrationsController#create as HTML
  Parameters: {"name"=>"hoge", "password"=>"[FILTERED]", "registration"=>{"name"=>"hoge", "password"=>"[FILTERED]"}}
[31mUnpermitted parameters: :name, :registration[0m
[31mUnpermitted parameters: :name, :registration[0m
[31mUnpermitted parameters: :name, :registration[0m
  [1m[35m (0.4ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Exists? (0.7ms)[0m  [1m[34mSELECT 1 AS one FROM `users` WHERE `users`.`email` IS NULL AND `users`.`provider` = 'email' LIMIT 1[0m
  [1m[35m (0.3ms)[0m  [1m[31mROLLBACK[0m
Completed 422 Unprocessable Entity in 175ms (Views: 0.3ms | ActiveRecord: 19.0ms | Allocations: 20194)


Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:36:35 +0000
Processing by DeviseTokenAuth::RegistrationsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "registration"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
DEPRECATION WARNING: Uniqueness validator will no longer enforce case sensitive comparison in Rails 6.1. To continue case sensitive comparison on the :email attribute in User model, pass `case_sensitive: true` option explicitly to the uniqueness validator. (called from block in build_relation at /home/web/app/vendor/bundle/ruby/2.6.0/gems/activerecord-6.0.2.1/lib/active_record/validations/uniqueness.rb:64)
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Exists? (3.8ms)[0m  [1m[34mSELECT 1 AS one FROM `users` WHERE `users`.`email` = BINARY 'hoge@aaa.bb.cc' AND `users`.`provider` = 'email' LIMIT 1[0m
  [1m[36mUser Create (5.3ms)[0m  [1m[32mINSERT INTO `users` (`uid`, `encrypted_password`, `email`, `created_at`, `updated_at`) VALUES ('hoge@aaa.bb.cc', '$2a$11$3N3g4NpFipTvsbzc9anWb.9iu7CX/EyzmW6R7oeoGXraFlioekT7.', 'hoge@aaa.bb.cc', '2020-02-11 07:36:35.411431', '2020-02-11 07:36:35.411431')[0m
  [1m[35m (0.9ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.6ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"_Hs6UsoYH2lQ2NS1o2MISQ\":{\"token\":\"$2a$10$dn5yfdKFC4Fa6IIf6i5e/exmzOR9iyH/Va4N3JJgGgqyLp60zCYWG\",\"expiry\":1582616195}}', `users`.`updated_at` = '2020-02-11 07:36:35.486400' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.4ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 233ms (Views: 0.2ms | ActiveRecord: 12.2ms | Allocations: 11607)


  [1m[35m (0.5ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` LIMIT 11[0m
Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:37:06 +0000
Processing by DeviseTokenAuth::RegistrationsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "registration"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
DEPRECATION WARNING: Uniqueness validator will no longer enforce case sensitive comparison in Rails 6.1. To continue case sensitive comparison on the :email attribute in User model, pass `case_sensitive: true` option explicitly to the uniqueness validator. (called from block in build_relation at /home/web/app/vendor/bundle/ruby/2.6.0/gems/activerecord-6.0.2.1/lib/active_record/validations/uniqueness.rb:64)
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Exists? (0.5ms)[0m  [1m[34mSELECT 1 AS one FROM `users` WHERE `users`.`email` = BINARY 'hoge@aaa.bb.cc' AND `users`.`provider` = 'email' LIMIT 1[0m
  [1m[35m (0.3ms)[0m  [1m[31mROLLBACK[0m
Completed 422 Unprocessable Entity in 126ms (Views: 0.2ms | ActiveRecord: 1.0ms | Allocations: 5451)


Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:40:47 +0000
Processing by DeviseTokenAuth::RegistrationsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "registration"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
DEPRECATION WARNING: Uniqueness validator will no longer enforce case sensitive comparison in Rails 6.1. To continue case sensitive comparison on the :email attribute in User model, pass `case_sensitive: true` option explicitly to the uniqueness validator. (called from block in build_relation at /home/web/app/vendor/bundle/ruby/2.6.0/gems/activerecord-6.0.2.1/lib/active_record/validations/uniqueness.rb:64)
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Exists? (0.6ms)[0m  [1m[34mSELECT 1 AS one FROM `users` WHERE `users`.`email` = BINARY 'hoge@aaa.bb.cc' AND `users`.`provider` = 'email' LIMIT 1[0m
  [1m[35m (0.2ms)[0m  [1m[31mROLLBACK[0m
Completed 422 Unprocessable Entity in 127ms (Views: 0.3ms | ActiveRecord: 1.1ms | Allocations: 5390)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 07:41:13 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (1.2ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (6.7ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"_Hs6UsoYH2lQ2NS1o2MISQ\":{\"token\":\"$2a$10$dn5yfdKFC4Fa6IIf6i5e/exmzOR9iyH/Va4N3JJgGgqyLp60zCYWG\",\"expiry\":1582616195},\"AynI6VKiHmt9awVKBN5wiA\":{\"token\":\"$2a$10$/yY.EAZzcstGvcddS3axCOeVbfCfm5SgNU2lWoC3qPcM/aoHgpI2q\",\"expiry\":1582616473}}', `users`.`updated_at` = '2020-02-11 07:41:13.924774' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
Completed 500 Internal Server Error in 216ms (ActiveRecord: 9.2ms | Allocations: 8231)


  
NoMethodError (undefined method `current_sign_in_at' for #<User:0x00007fe788172dd0>):
  
activemodel (6.0.2.1) lib/active_model/attribute_methods.rb:431:in `method_missing'
devise (4.7.1) lib/devise/models/trackable.rb:21:in `update_tracked_fields'
devise (4.7.1) lib/devise/models/trackable.rb:39:in `update_tracked_fields!'
devise (4.7.1) lib/devise/hooks/trackable.rb:9:in `block in <main>'
warden (1.2.8) lib/warden/hooks.rb:15:in `block in _run_callbacks'
warden (1.2.8) lib/warden/hooks.rb:10:in `each'
warden (1.2.8) lib/warden/hooks.rb:10:in `_run_callbacks'
warden (1.2.8) lib/warden/manager.rb:52:in `_run_callbacks'
warden (1.2.8) lib/warden/proxy.rb:191:in `set_user'
devise (4.7.1) lib/devise/controllers/sign_in_out.rb:53:in `sign_in'
devise_token_auth (1.1.3) app/controllers/devise_token_auth/sessions_controller.rb:32:in `create'
actionpack (6.0.2.1) lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
actionpack (6.0.2.1) lib/abstract_controller/base.rb:196:in `process_action'
actionpack (6.0.2.1) lib/action_controller/metal/rendering.rb:30:in `process_action'
actionpack (6.0.2.1) lib/abstract_controller/callbacks.rb:42:in `block in process_action'
activesupport (6.0.2.1) lib/active_support/callbacks.rb:135:in `run_callbacks'
actionpack (6.0.2.1) lib/abstract_controller/callbacks.rb:41:in `process_action'
actionpack (6.0.2.1) lib/action_controller/metal/rescue.rb:22:in `process_action'
actionpack (6.0.2.1) lib/action_controller/metal/instrumentation.rb:33:in `block in process_action'
activesupport (6.0.2.1) lib/active_support/notifications.rb:180:in `block in instrument'
activesupport (6.0.2.1) lib/active_support/notifications/instrumenter.rb:24:in `instrument'
activesupport (6.0.2.1) lib/active_support/notifications.rb:180:in `instrument'
actionpack (6.0.2.1) lib/action_controller/metal/instrumentation.rb:32:in `process_action'
actionpack (6.0.2.1) lib/action_controller/metal/params_wrapper.rb:245:in `process_action'
activerecord (6.0.2.1) lib/active_record/railties/controller_runtime.rb:27:in `process_action'
actionpack (6.0.2.1) lib/abstract_controller/base.rb:136:in `process'
actionpack (6.0.2.1) lib/action_controller/metal.rb:191:in `dispatch'
actionpack (6.0.2.1) lib/action_controller/metal.rb:252:in `dispatch'
actionpack (6.0.2.1) lib/action_dispatch/routing/route_set.rb:51:in `dispatch'
actionpack (6.0.2.1) lib/action_dispatch/routing/route_set.rb:33:in `serve'
actionpack (6.0.2.1) lib/action_dispatch/routing/mapper.rb:18:in `block in <class:Constraints>'
actionpack (6.0.2.1) lib/action_dispatch/routing/mapper.rb:48:in `serve'
actionpack (6.0.2.1) lib/action_dispatch/journey/router.rb:49:in `block in serve'
actionpack (6.0.2.1) lib/action_dispatch/journey/router.rb:32:in `each'
actionpack (6.0.2.1) lib/action_dispatch/journey/router.rb:32:in `serve'
actionpack (6.0.2.1) lib/action_dispatch/routing/route_set.rb:837:in `call'
warden (1.2.8) lib/warden/manager.rb:36:in `block in call'
warden (1.2.8) lib/warden/manager.rb:34:in `catch'
warden (1.2.8) lib/warden/manager.rb:34:in `call'
rack (2.2.2) lib/rack/etag.rb:27:in `call'
rack (2.2.2) lib/rack/conditional_get.rb:40:in `call'
rack (2.2.2) lib/rack/head.rb:12:in `call'
activerecord (6.0.2.1) lib/active_record/migration.rb:567:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'
activesupport (6.0.2.1) lib/active_support/callbacks.rb:101:in `run_callbacks'
actionpack (6.0.2.1) lib/action_dispatch/middleware/callbacks.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/actionable_exceptions.rb:17:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:32:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
rack-cors (1.1.1) lib/rack/cors.rb:100:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
  [1m[35m (0.5ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  [1m[35m (0.2ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  [1m[35m (0.5ms)[0m  [1m[34mSELECT GET_LOCK('5277929974492897470', 0)[0m
  [1m[35m (0.4ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Migrating to DeviseTokenAuthCreateUsers (20200211063250)
  [1m[35m (10.6ms)[0m  [1m[35mDROP INDEX `index_users_on_confirmation_token` ON `users`[0m
  [1m[35m (2.6ms)[0m  [1m[35mDROP INDEX `index_users_on_reset_password_token` ON `users`[0m
  [1m[35m (2.4ms)[0m  [1m[35mDROP INDEX `index_users_on_uid_and_provider` ON `users`[0m
  [1m[35m (2.9ms)[0m  [1m[35mDROP INDEX `index_users_on_email` ON `users`[0m
  [1m[35m (3.6ms)[0m  [1m[35mDROP TABLE `users`[0m
  [1m[36mprimary::SchemaMigration Destroy (1.2ms)[0m  [1m[31mDELETE FROM `schema_migrations` WHERE `schema_migrations`.`version` = '20200211063250'[0m
  [1m[35m (0.4ms)[0m  [1m[34mSELECT RELEASE_LOCK('5277929974492897470')[0m
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.2ms)[0m  [1m[34mSELECT GET_LOCK('5277929974492897470', 0)[0m
  [1m[35m (0.2ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Migrating to DeviseTokenAuthCreateUsers (20200211063250)
  [1m[35m (4.3ms)[0m  [1m[35mCREATE TABLE `users` (`id` bigint NOT NULL AUTO_INCREMENT PRIMARY KEY, `provider` varchar(255) DEFAULT 'email' NOT NULL, `uid` varchar(255) DEFAULT '' NOT NULL, `encrypted_password` varchar(255) DEFAULT '' NOT NULL, `reset_password_token` varchar(255), `reset_password_sent_at` datetime, `allow_password_change` tinyint(1) DEFAULT FALSE, `remember_created_at` datetime, `sign_in_count` int DEFAULT 0 NOT NULL, `current_sign_in_at` datetime, `last_sign_in_at` datetime, `current_sign_in_ip` varchar(255), `last_sign_in_ip` varchar(255), `confirmation_token` varchar(255), `confirmed_at` datetime, `confirmation_sent_at` datetime, `unconfirmed_email` varchar(255), `name` varchar(255), `nickname` varchar(255), `image` varchar(255), `email` varchar(255), `tokens` text, `created_at` datetime(6) NOT NULL, `updated_at` datetime(6) NOT NULL)[0m
  [1m[35m (3.2ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_email`  ON `users` (`email`) [0m
  [1m[35m (3.1ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_uid_and_provider`  ON `users` (`uid`, `provider`) [0m
  [1m[35m (3.0ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_reset_password_token`  ON `users` (`reset_password_token`) [0m
  [1m[35m (3.0ms)[0m  [1m[35mCREATE UNIQUE INDEX `index_users_on_confirmation_token`  ON `users` (`confirmation_token`) [0m
  [1m[35m (0.1ms)[0m  [1m[35mBEGIN[0m
  [1m[36mprimary::SchemaMigration Create (0.3ms)[0m  [1m[32mINSERT INTO `schema_migrations` (`version`) VALUES ('20200211063250')[0m
  [1m[35m (0.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mActiveRecord::InternalMetadata Load (0.4ms)[0m  [1m[34mSELECT `ar_internal_metadata`.* FROM `ar_internal_metadata` WHERE `ar_internal_metadata`.`key` = 'environment' LIMIT 1[0m
  [1m[35m (0.4ms)[0m  [1m[34mSELECT RELEASE_LOCK('5277929974492897470')[0m
  [1m[35m (0.3ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:43:37 +0000
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  
ArgumentError (Before process_action callback :verify_authenticity_token has not been defined):
  
app/controllers/application_controller.rb:3:in `<class:ApplicationController>'
app/controllers/application_controller.rb:1:in `<main>'
Started POST "/auth" for 172.18.0.1 at 2020-02-11 07:44:06 +0000
Processing by DeviseTokenAuth::RegistrationsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "registration"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
[31mUnpermitted parameter: :registration[0m
DEPRECATION WARNING: Uniqueness validator will no longer enforce case sensitive comparison in Rails 6.1. To continue case sensitive comparison on the :email attribute in User model, pass `case_sensitive: true` option explicitly to the uniqueness validator. (called from block in build_relation at /home/web/app/vendor/bundle/ruby/2.6.0/gems/activerecord-6.0.2.1/lib/active_record/validations/uniqueness.rb:64)
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Exists? (0.5ms)[0m  [1m[34mSELECT 1 AS one FROM `users` WHERE `users`.`email` = BINARY 'hoge@aaa.bb.cc' AND `users`.`provider` = 'email' LIMIT 1[0m
  [1m[36mUser Create (0.4ms)[0m  [1m[32mINSERT INTO `users` (`uid`, `encrypted_password`, `email`, `created_at`, `updated_at`) VALUES ('hoge@aaa.bb.cc', '$2a$11$oxdQu4LZdyj9uoaupx4WmOhmUpa96QDI7p6zTDMp4bL/AFhy.j22y', 'hoge@aaa.bb.cc', '2020-02-11 07:44:06.214348', '2020-02-11 07:44:06.214348')[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646}}', `users`.`updated_at` = '2020-02-11 07:44:06.279527' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 226ms (Views: 0.2ms | ActiveRecord: 5.6ms | Allocations: 26725)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 07:44:08 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.4ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648}}', `users`.`updated_at` = '2020-02-11 07:44:08.862865' WHERE `users`.`id` = 1[0m
  [1m[35m (0.9ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 1, `users`.`current_sign_in_at` = '2020-02-11 07:44:08', `users`.`last_sign_in_at` = '2020-02-11 07:44:08', `users`.`current_sign_in_ip` = '172.18.0.1', `users`.`last_sign_in_ip` = '172.18.0.1', `users`.`updated_at` = '2020-02-11 07:44:08.877520' WHERE `users`.`id` = 1[0m
  [1m[35m (1.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 208ms (Views: 0.3ms | ActiveRecord: 5.0ms | Allocations: 11266)


  [1m[35m (0.5ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` LIMIT 11[0m
Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 07:47:17 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (1.0ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837}}', `users`.`updated_at` = '2020-02-11 07:47:17.595800' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 2, `users`.`current_sign_in_at` = '2020-02-11 07:47:17', `users`.`updated_at` = '2020-02-11 07:47:17.601858' WHERE `users`.`id` = 1[0m
  [1m[35m (0.6ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.3ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 198ms (Views: 0.2ms | ActiveRecord: 4.1ms | Allocations: 11177)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 07:49:32 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.6ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972}}', `users`.`updated_at` = '2020-02-11 07:49:32.491621' WHERE `users`.`id` = 1[0m
  [1m[35m (1.5ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 3, `users`.`current_sign_in_at` = '2020-02-11 07:49:32', `users`.`last_sign_in_at` = '2020-02-11 07:47:17', `users`.`updated_at` = '2020-02-11 07:49:32.498607' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.4ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 198ms (Views: 0.2ms | ActiveRecord: 5.0ms | Allocations: 11279)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 07:50:04 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004}}', `users`.`updated_at` = '2020-02-11 07:50:04.465785' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 4, `users`.`current_sign_in_at` = '2020-02-11 07:50:04', `users`.`last_sign_in_at` = '2020-02-11 07:49:32', `users`.`updated_at` = '2020-02-11 07:50:04.472403' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.4ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 205ms (Views: 0.2ms | ActiveRecord: 4.4ms | Allocations: 11358)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 07:51:34 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.7ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094}}', `users`.`updated_at` = '2020-02-11 07:51:34.369170' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 5, `users`.`current_sign_in_at` = '2020-02-11 07:51:34', `users`.`last_sign_in_at` = '2020-02-11 07:50:04', `users`.`updated_at` = '2020-02-11 07:51:34.375712' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.4ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 197ms (Views: 0.2ms | ActiveRecord: 4.4ms | Allocations: 11443)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:04:54 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by DeviseTokenAuth::SessionsController#create as JSON
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.9ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894}}', `users`.`updated_at` = '2020-02-11 08:04:54.413182' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 6, `users`.`current_sign_in_at` = '2020-02-11 08:04:54', `users`.`last_sign_in_at` = '2020-02-11 07:51:34', `users`.`updated_at` = '2020-02-11 08:04:54.419954' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 201ms (Views: 0.2ms | ActiveRecord: 4.8ms | Allocations: 11531)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:10:13 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213}}', `users`.`updated_at` = '2020-02-11 08:10:13.881082' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 7, `users`.`current_sign_in_at` = '2020-02-11 08:10:13', `users`.`last_sign_in_at` = '2020-02-11 08:04:54', `users`.`updated_at` = '2020-02-11 08:10:13.887632' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.7ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 206ms (Views: 0.3ms | ActiveRecord: 4.5ms | Allocations: 11615)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:29:16 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (1.3ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.8ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356}}', `users`.`updated_at` = '2020-02-11 08:29:16.819975' WHERE `users`.`id` = 1[0m
  [1m[35m (1.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.6ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 8, `users`.`current_sign_in_at` = '2020-02-11 08:29:16', `users`.`last_sign_in_at` = '2020-02-11 08:10:13', `users`.`updated_at` = '2020-02-11 08:29:16.827720' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 210ms (Views: 0.3ms | ActiveRecord: 5.9ms | Allocations: 11698)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:30:50 +0000
  [1m[35m (0.7ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (2.1ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.7ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Z3EeRFKq2ruolG-RDO7O3w\":{\"token\":\"$2a$10$.lLGfB6umD/qKuL02HlRweBWGwuT9NXLxd71FUri8U0NNRYu0psvu\",\"expiry\":1582616646},\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450}}', `users`.`updated_at` = '2020-02-11 08:30:50.298907' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 9, `users`.`current_sign_in_at` = '2020-02-11 08:30:50', `users`.`last_sign_in_at` = '2020-02-11 08:29:16', `users`.`updated_at` = '2020-02-11 08:30:50.312295' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 267ms (Views: 0.2ms | ActiveRecord: 8.3ms | Allocations: 29089)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:38:18 +0000
  [1m[35m (0.4ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (1.1ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"vlsVwKi1obiikvL6Cg-GVg\":{\"token\":\"$2a$10$KEnYXf0dL6e8RdUYG9mVfeSZ9NZuvxvxgrJCjnmKYtCZgt4SP8Wqi\",\"expiry\":1582616648},\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898}}', `users`.`updated_at` = '2020-02-11 08:38:18.720591' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 10, `users`.`current_sign_in_at` = '2020-02-11 08:38:18', `users`.`last_sign_in_at` = '2020-02-11 08:30:50', `users`.`updated_at` = '2020-02-11 08:38:18.727505' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 202ms (Views: 0.2ms | ActiveRecord: 4.5ms | Allocations: 11955)


Started GET "/auth/validate_token" for 172.18.0.1 at 2020-02-11 08:50:42 +0000
  [1m[35m (6.3ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
Processing by DeviseTokenAuth::TokenValidationsController#validate_token as HTML
Completed 401 Unauthorized in 13ms (Views: 0.3ms | ActiveRecord: 0.0ms | Allocations: 6014)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:51:02 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (2.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (2.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"qrC5xk-2u0pZ1BHyztIEwg\":{\"token\":\"$2a$10$OtWrLgFY29RLM6nc28kgVuCuONhg4ITE8l9CRWqFfLrfGFYRjSJnC\",\"expiry\":1582616837},\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662}}', `users`.`updated_at` = '2020-02-11 08:51:02.906819' WHERE `users`.`id` = 1[0m
  [1m[35m (0.9ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 11, `users`.`current_sign_in_at` = '2020-02-11 08:51:02', `users`.`last_sign_in_at` = '2020-02-11 08:38:18', `users`.`updated_at` = '2020-02-11 08:51:02.915723' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 205ms (Views: 0.2ms | ActiveRecord: 8.2ms | Allocations: 11959)


Started GET "/auth/validate_token" for 172.18.0.1 at 2020-02-11 08:51:04 +0000
Processing by DeviseTokenAuth::TokenValidationsController#validate_token as HTML
Completed 401 Unauthorized in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 183)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:51:16 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"qx6_LYWyJ5AgBRW1jrOdPg\":{\"token\":\"$2a$10$HPNXaG5jV7/hUHH9QUEqnuWTbMLyxYWBeMCTE8oIQodtOl/wbtN2O\",\"expiry\":1582616972},\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676}}', `users`.`updated_at` = '2020-02-11 08:51:16.916578' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 12, `users`.`current_sign_in_at` = '2020-02-11 08:51:16', `users`.`last_sign_in_at` = '2020-02-11 08:51:02', `users`.`updated_at` = '2020-02-11 08:51:16.923093' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 203ms (Views: 0.2ms | ActiveRecord: 3.9ms | Allocations: 11937)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:52:38 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"KeGbQ6Hn2VPa-vw0jpDPAg\":{\"token\":\"$2a$10$YlmbfPmxLPhDk94vBdJavODIWN6J/5u.WHgFScFm8SvVgFwBiuYFe\",\"expiry\":1582617004},\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759}}', `users`.`updated_at` = '2020-02-11 08:52:39.067031' WHERE `users`.`id` = 1[0m
  [1m[35m (1.0ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 13, `users`.`current_sign_in_at` = '2020-02-11 08:52:39', `users`.`last_sign_in_at` = '2020-02-11 08:51:16', `users`.`updated_at` = '2020-02-11 08:52:39.073954' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 202ms (Views: 0.2ms | ActiveRecord: 4.1ms | Allocations: 11937)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:53:08 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (2.0ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"86gTq7gy57ZsZdH8x5ugMg\":{\"token\":\"$2a$10$TKWBfGKOZiHUV4b44l1jFOdH7h08T4heK/PKzR4mghbS7NKZavlfa\",\"expiry\":1582617094},\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788}}', `users`.`updated_at` = '2020-02-11 08:53:08.386370' WHERE `users`.`id` = 1[0m
  [1m[35m (1.0ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 14, `users`.`current_sign_in_at` = '2020-02-11 08:53:08', `users`.`last_sign_in_at` = '2020-02-11 08:52:39', `users`.`updated_at` = '2020-02-11 08:53:08.393205' WHERE `users`.`id` = 1[0m
  [1m[35m (0.6ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 203ms (Views: 0.2ms | ActiveRecord: 5.5ms | Allocations: 11949)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:53:11 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.7ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"0oz6OWJ1veBDzsdPRR4rYw\":{\"token\":\"$2a$10$rxbfjg1Gc.Gn2trWKS3BV.8c/id4Qx4OfTXgYLybX22jaYa/hJXYy\",\"expiry\":1582617894},\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791}}', `users`.`updated_at` = '2020-02-11 08:53:11.391563' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 15, `users`.`current_sign_in_at` = '2020-02-11 08:53:11', `users`.`last_sign_in_at` = '2020-02-11 08:53:08', `users`.`updated_at` = '2020-02-11 08:53:11.398355' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.8ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 204ms (Views: 0.3ms | ActiveRecord: 4.6ms | Allocations: 11936)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:53:15 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"w_8fNbzgwf8uIKH6bESo7Q\":{\"token\":\"$2a$10$UaR0rHStf1VMvoXi4CJZxeQQLv6rat5NRe0UJMk00c6ShGf0l9nya\",\"expiry\":1582618213},\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795}}', `users`.`updated_at` = '2020-02-11 08:53:16.000607' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 16, `users`.`current_sign_in_at` = '2020-02-11 08:53:16', `users`.`last_sign_in_at` = '2020-02-11 08:53:11', `users`.`updated_at` = '2020-02-11 08:53:16.007083' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 210ms (Views: 0.2ms | ActiveRecord: 3.9ms | Allocations: 11957)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:54:39 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.6ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"jK30_oQQBp7MSM7IGpwEiw\":{\"token\":\"$2a$10$23oJG.6udeWpa541ur2IC.Q8R8uOIBgKYefwrbIT0h0YuQY2ZPSH6\",\"expiry\":1582619356},\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880}}', `users`.`updated_at` = '2020-02-11 08:54:40.099493' WHERE `users`.`id` = 1[0m
  [1m[35m (1.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 17, `users`.`current_sign_in_at` = '2020-02-11 08:54:40', `users`.`last_sign_in_at` = '2020-02-11 08:53:16', `users`.`updated_at` = '2020-02-11 08:54:40.106755' WHERE `users`.`id` = 1[0m
  [1m[35m (2.5ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 204ms (Views: 0.2ms | ActiveRecord: 6.8ms | Allocations: 11936)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:55:13 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"Oj2CPawygIhMWNrVx1PgvQ\":{\"token\":\"$2a$10$Gkz8li5ataaUdnEbnuriYuc1ZndlHHk21HFi5PNI4SB.KvIT9eX8O\",\"expiry\":1582619450},\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913}}', `users`.`updated_at` = '2020-02-11 08:55:13.531299' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 18, `users`.`current_sign_in_at` = '2020-02-11 08:55:13', `users`.`last_sign_in_at` = '2020-02-11 08:54:40', `users`.`updated_at` = '2020-02-11 08:55:13.537985' WHERE `users`.`id` = 1[0m
  [1m[35m (0.6ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 199ms (Views: 0.2ms | ActiveRecord: 4.2ms | Allocations: 11936)


Started GET "/auth/validate_token" for 172.18.0.1 at 2020-02-11 08:55:16 +0000
Processing by DeviseTokenAuth::TokenValidationsController#validate_token as HTML
Completed 401 Unauthorized in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 181)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:55:53 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"zU94CR3PUJY0_j5NHBlsiQ\":{\"token\":\"$2a$10$Z4.SdxLWk2GS60UlxZhgbutaifD69FAgdBTaI7Fs9YTwODIx3QJbS\",\"expiry\":1582619898},\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953}}', `users`.`updated_at` = '2020-02-11 08:55:53.613862' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 19, `users`.`current_sign_in_at` = '2020-02-11 08:55:53', `users`.`last_sign_in_at` = '2020-02-11 08:55:13', `users`.`updated_at` = '2020-02-11 08:55:53.620480' WHERE `users`.`id` = 1[0m
  [1m[35m (0.6ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 198ms (Views: 0.2ms | ActiveRecord: 4.1ms | Allocations: 11939)


Started GET "/auth/validate_token" for 172.18.0.1 at 2020-02-11 08:55:58 +0000
Processing by DeviseTokenAuth::TokenValidationsController#validate_token as HTML
Completed 401 Unauthorized in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 181)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:57:32 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"G2qH6yPWLwL6x1OL280eoQ\":{\"token\":\"$2a$10$VduGxtI.8vZWxS0uteoCo.ezCXkE6QkrGyfYW.KH5GYtdL6xJRnci\",\"expiry\":1582620662},\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053}}', `users`.`updated_at` = '2020-02-11 08:57:33.085358' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 20, `users`.`current_sign_in_at` = '2020-02-11 08:57:33', `users`.`last_sign_in_at` = '2020-02-11 08:55:53', `users`.`updated_at` = '2020-02-11 08:57:33.092142' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 199ms (Views: 0.2ms | ActiveRecord: 4.6ms | Allocations: 11936)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:58:37 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"P96rQbUNFi3aNm88sZaoDg\":{\"token\":\"$2a$10$0L.tsl9UwcXt.ATqNEQZLeU5oWzsRAL5gD0iBykNbtMF7iw5nV4pu\",\"expiry\":1582620676},\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117}}', `users`.`updated_at` = '2020-02-11 08:58:37.596517' WHERE `users`.`id` = 1[0m
  [1m[35m (1.2ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 21, `users`.`current_sign_in_at` = '2020-02-11 08:58:37', `users`.`last_sign_in_at` = '2020-02-11 08:57:33', `users`.`updated_at` = '2020-02-11 08:58:37.603227' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 197ms (Views: 0.2ms | ActiveRecord: 4.3ms | Allocations: 11936)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:58:58 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.7ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"d8LftGfP_8dNvIMvVQZSKg\":{\"token\":\"$2a$10$v1OCMLcchw0MlE44BjX7sOeSWsc4vCzj3Qjr6MYaKa73.FeC4F5Cu\",\"expiry\":1582620759},\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138}}', `users`.`updated_at` = '2020-02-11 08:58:58.551386' WHERE `users`.`id` = 1[0m
  [1m[35m (1.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 22, `users`.`current_sign_in_at` = '2020-02-11 08:58:58', `users`.`last_sign_in_at` = '2020-02-11 08:58:37', `users`.`updated_at` = '2020-02-11 08:58:58.558361' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 199ms (Views: 0.2ms | ActiveRecord: 4.7ms | Allocations: 11936)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:59:14 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.7ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"9HFy27hVDQfz-jcnlox1fA\":{\"token\":\"$2a$10$E0heojVO1t7WXCRwtzW2x.CYxq0sPEYJKkat2WK7TM99Zjl8Apuvq\",\"expiry\":1582620788},\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154}}', `users`.`updated_at` = '2020-02-11 08:59:14.686647' WHERE `users`.`id` = 1[0m
  [1m[35m (1.0ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 23, `users`.`current_sign_in_at` = '2020-02-11 08:59:14', `users`.`last_sign_in_at` = '2020-02-11 08:58:58', `users`.`updated_at` = '2020-02-11 08:59:14.701055' WHERE `users`.`id` = 1[0m
  [1m[35m (0.9ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 207ms (Views: 0.2ms | ActiveRecord: 4.6ms | Allocations: 11938)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 08:59:23 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"lLjwId0wlx2rHH0oSNdJZw\":{\"token\":\"$2a$10$I3P6O2wYQgjFWllSrdxyY.65MQIVZ/A6CboX60MPtmJz2BVk60GUK\",\"expiry\":1582620791},\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163}}', `users`.`updated_at` = '2020-02-11 08:59:23.372337' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.4ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 24, `users`.`current_sign_in_at` = '2020-02-11 08:59:23', `users`.`last_sign_in_at` = '2020-02-11 08:59:14', `users`.`updated_at` = '2020-02-11 08:59:23.379578' WHERE `users`.`id` = 1[0m
  [1m[35m (0.9ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (1.1ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 207ms (Views: 0.2ms | ActiveRecord: 5.2ms | Allocations: 11937)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 09:00:15 +0000
  [1m[35m (0.5ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.4ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.4ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"w3iBLtvDYvhWso0g4asEvw\":{\"token\":\"$2a$10$5NjPxmUq490WJDzQvGqKR.0669itdVtP1oCRd54iubD3yrdIOIyM6\",\"expiry\":1582620795},\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163},\"_Gwcqkq_Lo4JLO-m2Iwqlg\":{\"token\":\"$2a$10$KXXFRScczgIx21s7UOP1VOMfC5QGGrKPjpaNU0Kk758mUqnVSk.wG\",\"expiry\":1582621215}}', `users`.`updated_at` = '2020-02-11 09:00:15.957517' WHERE `users`.`id` = 1[0m
  [1m[35m (3.2ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 25, `users`.`current_sign_in_at` = '2020-02-11 09:00:15', `users`.`last_sign_in_at` = '2020-02-11 08:59:23', `users`.`updated_at` = '2020-02-11 09:00:15.974583' WHERE `users`.`id` = 1[0m
  [1m[35m (1.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 255ms (Views: 0.2ms | ActiveRecord: 8.9ms | Allocations: 29243)


Started GET "/auth/validate_token" for 172.18.0.1 at 2020-02-11 09:00:22 +0000
Processing by DeviseTokenAuth::TokenValidationsController#validate_token as HTML
  [1m[36mUser Load (1.2ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`uid` = 'hoge@aaa.bb.cc' LIMIT 1[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 70ms (Views: 0.3ms | ActiveRecord: 1.7ms | Allocations: 3137)


Started GET "/post" for 172.18.0.1 at 2020-02-11 09:05:02 +0000
  [1m[35m (0.6ms)[0m  [1m[35mSET NAMES utf8mb4,  @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'),  @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483[0m
  [1m[35m (0.3ms)[0m  [1m[34mSELECT `schema_migrations`.`version` FROM `schema_migrations` ORDER BY `schema_migrations`.`version` ASC[0m
  
ActionController::RoutingError (No route matches [GET] "/post"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
rack-cors (1.1.1) lib/rack/cors.rb:100:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:05:20 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms | Allocations: 265)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:05:35 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 9ms (Views: 9.2ms | ActiveRecord: 0.0ms | Allocations: 164)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:05:43 +0000
Processing by PostsController#index as HTML
Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 162)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:05:58 +0000
Processing by PostsController#index as HTML
Filter chain halted as :authenticate_user! rendered or redirected
Completed 401 Unauthorized in 18ms (Views: 0.8ms | ActiveRecord: 0.0ms | Allocations: 5989)


Started GET "/post" for 172.18.0.1 at 2020-02-11 09:06:13 +0000
  
ActionController::RoutingError (No route matches [GET] "/post"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
rack-cors (1.1.1) lib/rack/cors.rb:100:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 09:06:18 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.7ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.6ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"tAlZYI2lYJVQsHIvcBOW7w\":{\"token\":\"$2a$10$ENA8sj/rJR90zKmR4.KSh.ZqEzoOWupDufMWcLO.S0XQCHP942zJe\",\"expiry\":1582620880},\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163},\"_Gwcqkq_Lo4JLO-m2Iwqlg\":{\"token\":\"$2a$10$KXXFRScczgIx21s7UOP1VOMfC5QGGrKPjpaNU0Kk758mUqnVSk.wG\",\"expiry\":1582621215},\"eBMosMqxsAaePqnu9q1IGw\":{\"token\":\"$2a$10$T7aeBgJr5QkbWJUU027uv.r7Qk/8gx4b7rl5lg/fbOJn3MoyKeWkm\",\"expiry\":1582621579}}', `users`.`updated_at` = '2020-02-11 09:06:19.071017' WHERE `users`.`id` = 1[0m
  [1m[35m (1.1ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 26, `users`.`current_sign_in_at` = '2020-02-11 09:06:19', `users`.`last_sign_in_at` = '2020-02-11 09:00:15', `users`.`updated_at` = '2020-02-11 09:06:19.086563' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 231ms (Views: 0.2ms | ActiveRecord: 6.4ms | Allocations: 29236)


Started GET "/post" for 172.18.0.1 at 2020-02-11 09:06:20 +0000
  
ActionController::RoutingError (No route matches [GET] "/post"):
  
actionpack (6.0.2.1) lib/action_dispatch/middleware/debug_exceptions.rb:36:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
railties (6.0.2.1) lib/rails/rack/logger.rb:38:in `call_app'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `block in call'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `block in tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:28:in `tagged'
activesupport (6.0.2.1) lib/active_support/tagged_logging.rb:80:in `tagged'
railties (6.0.2.1) lib/rails/rack/logger.rb:26:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/request_id.rb:27:in `call'
rack (2.2.2) lib/rack/runtime.rb:22:in `call'
activesupport (6.0.2.1) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/executor.rb:14:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/static.rb:126:in `call'
rack (2.2.2) lib/rack/sendfile.rb:110:in `call'
actionpack (6.0.2.1) lib/action_dispatch/middleware/host_authorization.rb:83:in `call'
rack-cors (1.1.1) lib/rack/cors.rb:100:in `call'
railties (6.0.2.1) lib/rails/engine.rb:526:in `call'
puma (4.3.1) lib/puma/configuration.rb:228:in `call'
puma (4.3.1) lib/puma/server.rb:681:in `handle_request'
puma (4.3.1) lib/puma/server.rb:472:in `process_client'
puma (4.3.1) lib/puma/server.rb:328:in `block in run'
puma (4.3.1) lib/puma/thread_pool.rb:134:in `block in spawn_thread'
Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:06:39 +0000
Processing by PostsController#index as HTML
Filter chain halted as :authenticate_user! rendered or redirected
Completed 401 Unauthorized in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 155)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 09:06:46 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"2D1jAaoplxdIjuZssPvXYQ\":{\"token\":\"$2a$10$cWqb2FTTkyzmBhoWLpJEk.XqbBAYMnUBcIAHBBKFg3ouMHVbnLBBi\",\"expiry\":1582620913},\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163},\"_Gwcqkq_Lo4JLO-m2Iwqlg\":{\"token\":\"$2a$10$KXXFRScczgIx21s7UOP1VOMfC5QGGrKPjpaNU0Kk758mUqnVSk.wG\",\"expiry\":1582621215},\"eBMosMqxsAaePqnu9q1IGw\":{\"token\":\"$2a$10$T7aeBgJr5QkbWJUU027uv.r7Qk/8gx4b7rl5lg/fbOJn3MoyKeWkm\",\"expiry\":1582621579},\"8SesLWeMxtfZpxSwGgEz4g\":{\"token\":\"$2a$10$XPa44zZ5artuQOMbIooMYO.drmYvuv29BaRg955c1b57vsPHy.VEa\",\"expiry\":1582621606}}', `users`.`updated_at` = '2020-02-11 09:06:46.600232' WHERE `users`.`id` = 1[0m
  [1m[35m (1.5ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 27, `users`.`current_sign_in_at` = '2020-02-11 09:06:46', `users`.`last_sign_in_at` = '2020-02-11 09:06:19', `users`.`updated_at` = '2020-02-11 09:06:46.607653' WHERE `users`.`id` = 1[0m
  [1m[35m (0.8ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 203ms (Views: 0.3ms | ActiveRecord: 5.0ms | Allocations: 11956)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:06:48 +0000
Processing by PostsController#index as HTML
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`uid` = 'hoge@aaa.bb.cc' LIMIT 1[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 65ms (Views: 0.2ms | ActiveRecord: 1.0ms | Allocations: 2893)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 09:07:06 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"1Pw3GI6aAR4k5pLiSRIDgg\":{\"token\":\"$2a$10$bq8mYB2fIdF4MGvDm6VKg.IjNFBt2cgv7ejIIsp5J3IETdYibW8u6\",\"expiry\":1582620953},\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163},\"_Gwcqkq_Lo4JLO-m2Iwqlg\":{\"token\":\"$2a$10$KXXFRScczgIx21s7UOP1VOMfC5QGGrKPjpaNU0Kk758mUqnVSk.wG\",\"expiry\":1582621215},\"eBMosMqxsAaePqnu9q1IGw\":{\"token\":\"$2a$10$T7aeBgJr5QkbWJUU027uv.r7Qk/8gx4b7rl5lg/fbOJn3MoyKeWkm\",\"expiry\":1582621579},\"8SesLWeMxtfZpxSwGgEz4g\":{\"token\":\"$2a$10$XPa44zZ5artuQOMbIooMYO.drmYvuv29BaRg955c1b57vsPHy.VEa\",\"expiry\":1582621606},\"cjaRUgAOz7EZox6LqX5vIQ\":{\"token\":\"$2a$10$nf.4OaroIJs7RuT0d5eQseHLu3V/YAH.EMdiiwHuApdjgqDtBl7Mu\",\"expiry\":1582621626}}', `users`.`updated_at` = '2020-02-11 09:07:06.608461' WHERE `users`.`id` = 1[0m
  [1m[35m (1.3ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 28, `users`.`current_sign_in_at` = '2020-02-11 09:07:06', `users`.`last_sign_in_at` = '2020-02-11 09:06:46', `users`.`updated_at` = '2020-02-11 09:07:06.615479' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.4ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 197ms (Views: 0.2ms | ActiveRecord: 4.4ms | Allocations: 11936)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:07:07 +0000
Processing by PostsController#index as HTML
Filter chain halted as :authenticate_user! rendered or redirected
Completed 401 Unauthorized in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 172)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 09:07:14 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"CrYGE4zxwPwExJldFlW70g\":{\"token\":\"$2a$10$7GWaktffYO1/a1q.ayI3DuyNaoenjBBnPGBXjIcCGBwRXG/In3h0C\",\"expiry\":1582621053},\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163},\"_Gwcqkq_Lo4JLO-m2Iwqlg\":{\"token\":\"$2a$10$KXXFRScczgIx21s7UOP1VOMfC5QGGrKPjpaNU0Kk758mUqnVSk.wG\",\"expiry\":1582621215},\"eBMosMqxsAaePqnu9q1IGw\":{\"token\":\"$2a$10$T7aeBgJr5QkbWJUU027uv.r7Qk/8gx4b7rl5lg/fbOJn3MoyKeWkm\",\"expiry\":1582621579},\"8SesLWeMxtfZpxSwGgEz4g\":{\"token\":\"$2a$10$XPa44zZ5artuQOMbIooMYO.drmYvuv29BaRg955c1b57vsPHy.VEa\",\"expiry\":1582621606},\"cjaRUgAOz7EZox6LqX5vIQ\":{\"token\":\"$2a$10$nf.4OaroIJs7RuT0d5eQseHLu3V/YAH.EMdiiwHuApdjgqDtBl7Mu\",\"expiry\":1582621626},\"KS1-fjLXi50EodPYxPwNpA\":{\"token\":\"$2a$10$7N.u7KwA.1bIGem8.9NFaeqFxDIyoQuI/SJ8J2sqeFr4CkXyTKohq\",\"expiry\":1582621634}}', `users`.`updated_at` = '2020-02-11 09:07:14.797561' WHERE `users`.`id` = 1[0m
  [1m[35m (1.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 29, `users`.`current_sign_in_at` = '2020-02-11 09:07:14', `users`.`last_sign_in_at` = '2020-02-11 09:07:06', `users`.`updated_at` = '2020-02-11 09:07:14.804783' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 198ms (Views: 0.2ms | ActiveRecord: 4.7ms | Allocations: 11955)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:07:15 +0000
Processing by PostsController#index as HTML
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`uid` = 'hoge@aaa.bb.cc' LIMIT 1[0m
Filter chain halted as :authenticate_user! rendered or redirected
Completed 401 Unauthorized in 3ms (Views: 0.2ms | ActiveRecord: 0.5ms | Allocations: 1302)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 09:07:17 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"VAc4SYzw9xmITqYUAekWRg\":{\"token\":\"$2a$10$x4GOxkYjnADjWyTVAw2tR.NTrurkKsgEE.l367G0/Tn/lL4CfY38C\",\"expiry\":1582621117},\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163},\"_Gwcqkq_Lo4JLO-m2Iwqlg\":{\"token\":\"$2a$10$KXXFRScczgIx21s7UOP1VOMfC5QGGrKPjpaNU0Kk758mUqnVSk.wG\",\"expiry\":1582621215},\"eBMosMqxsAaePqnu9q1IGw\":{\"token\":\"$2a$10$T7aeBgJr5QkbWJUU027uv.r7Qk/8gx4b7rl5lg/fbOJn3MoyKeWkm\",\"expiry\":1582621579},\"8SesLWeMxtfZpxSwGgEz4g\":{\"token\":\"$2a$10$XPa44zZ5artuQOMbIooMYO.drmYvuv29BaRg955c1b57vsPHy.VEa\",\"expiry\":1582621606},\"cjaRUgAOz7EZox6LqX5vIQ\":{\"token\":\"$2a$10$nf.4OaroIJs7RuT0d5eQseHLu3V/YAH.EMdiiwHuApdjgqDtBl7Mu\",\"expiry\":1582621626},\"KS1-fjLXi50EodPYxPwNpA\":{\"token\":\"$2a$10$7N.u7KwA.1bIGem8.9NFaeqFxDIyoQuI/SJ8J2sqeFr4CkXyTKohq\",\"expiry\":1582621634},\"-lQWm1hL_M1V40i6swsV_w\":{\"token\":\"$2a$10$AkRbPRl5NFG9e18G8FzU8.y3KNl/wwrc.7NNypiD7ntdOJZCLlF5u\",\"expiry\":1582621637}}', `users`.`updated_at` = '2020-02-11 09:07:17.972236' WHERE `users`.`id` = 1[0m
  [1m[35m (0.9ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.2ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 30, `users`.`current_sign_in_at` = '2020-02-11 09:07:17', `users`.`last_sign_in_at` = '2020-02-11 09:07:14', `users`.`updated_at` = '2020-02-11 09:07:17.978565' WHERE `users`.`id` = 1[0m
  [1m[35m (0.7ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 197ms (Views: 0.3ms | ActiveRecord: 4.0ms | Allocations: 11940)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:07:18 +0000
Processing by PostsController#index as HTML
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`uid` = 'hoge@aaa.bb.cc' LIMIT 1[0m
Filter chain halted as :authenticate_user! rendered or redirected
Completed 401 Unauthorized in 3ms (Views: 0.2ms | ActiveRecord: 0.5ms | Allocations: 1303)


Started POST "/auth/sign_in" for 172.18.0.1 at 2020-02-11 09:07:23 +0000
Processing by DeviseTokenAuth::SessionsController#create as HTML
  Parameters: {"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]", "session"=>{"email"=>"hoge@aaa.bb.cc", "password"=>"[FILTERED]"}}
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE (BINARY email = 'hoge@aaa.bb.cc' AND provider= 'email') ORDER BY `users`.`id` ASC LIMIT 1[0m
[31mUnpermitted parameter: :session[0m
[31mUnpermitted parameter: :session[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.5ms)[0m  [1m[33mUPDATE `users` SET `users`.`tokens` = '{\"B3QeC6Ta2v45JisJB-_6-g\":{\"token\":\"$2a$10$bPDQUHmZ7i4U7DGd1..HqOzI83/vSWb.ReXo/raHSD3KlL59MfIsW\",\"expiry\":1582621138},\"2YwMP-frBQ4DwWvYpdMY0w\":{\"token\":\"$2a$10$gCZFE8VbQjN4WuIqrfyCku01J3cu5ALgglMmOdEH3j294Wyn3ko8G\",\"expiry\":1582621154},\"ySaBe7l2CmZoBD10CpLKIg\":{\"token\":\"$2a$10$yLAdtgcYiL1JRgNpFmv1SeIlDj9c/nhZzwU8M9MSVeSuIDdMbdvyi\",\"expiry\":1582621163},\"_Gwcqkq_Lo4JLO-m2Iwqlg\":{\"token\":\"$2a$10$KXXFRScczgIx21s7UOP1VOMfC5QGGrKPjpaNU0Kk758mUqnVSk.wG\",\"expiry\":1582621215},\"eBMosMqxsAaePqnu9q1IGw\":{\"token\":\"$2a$10$T7aeBgJr5QkbWJUU027uv.r7Qk/8gx4b7rl5lg/fbOJn3MoyKeWkm\",\"expiry\":1582621579},\"8SesLWeMxtfZpxSwGgEz4g\":{\"token\":\"$2a$10$XPa44zZ5artuQOMbIooMYO.drmYvuv29BaRg955c1b57vsPHy.VEa\",\"expiry\":1582621606},\"cjaRUgAOz7EZox6LqX5vIQ\":{\"token\":\"$2a$10$nf.4OaroIJs7RuT0d5eQseHLu3V/YAH.EMdiiwHuApdjgqDtBl7Mu\",\"expiry\":1582621626},\"KS1-fjLXi50EodPYxPwNpA\":{\"token\":\"$2a$10$7N.u7KwA.1bIGem8.9NFaeqFxDIyoQuI/SJ8J2sqeFr4CkXyTKohq\",\"expiry\":1582621634},\"-lQWm1hL_M1V40i6swsV_w\":{\"token\":\"$2a$10$AkRbPRl5NFG9e18G8FzU8.y3KNl/wwrc.7NNypiD7ntdOJZCLlF5u\",\"expiry\":1582621637},\"mfKBDAi4XhZe3nSn7ox41w\":{\"token\":\"$2a$10$MmMC7AJTnr3PyjWA7tTt6.bE8hUET.FY1.xekPa2NWUeDgrQJD7V.\",\"expiry\":1582621643}}', `users`.`updated_at` = '2020-02-11 09:07:23.276409' WHERE `users`.`id` = 1[0m
  [1m[35m (2.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[35m (0.3ms)[0m  [1m[35mBEGIN[0m
  [1m[36mUser Update (0.4ms)[0m  [1m[33mUPDATE `users` SET `users`.`sign_in_count` = 31, `users`.`current_sign_in_at` = '2020-02-11 09:07:23', `users`.`last_sign_in_at` = '2020-02-11 09:07:17', `users`.`updated_at` = '2020-02-11 09:07:23.284702' WHERE `users`.`id` = 1[0m
  [1m[35m (1.4ms)[0m  [1m[35mCOMMIT[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 200ms (Views: 0.3ms | ActiveRecord: 6.4ms | Allocations: 11955)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:07:24 +0000
Processing by PostsController#index as HTML
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`uid` = 'hoge@aaa.bb.cc' LIMIT 1[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 64ms (Views: 0.2ms | ActiveRecord: 1.0ms | Allocations: 2742)


Started GET "/posts" for 172.18.0.1 at 2020-02-11 09:07:46 +0000
Processing by PostsController#index as HTML
  [1m[36mUser Load (0.6ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`uid` = 'hoge@aaa.bb.cc' LIMIT 1[0m
  [1m[36mUser Load (0.5ms)[0m  [1m[34mSELECT `users`.* FROM `users` WHERE `users`.`id` = 1 LIMIT 1[0m
Completed 200 OK in 97ms (Views: 0.5ms | ActiveRecord: 2.8ms | Allocations: 17812)


