Kaui throws We're sorry, but something went wrong.
504 views
Skip to first unread message
Devi Kalaiselvan
Apr 21, 2021, 3:17:24 PM4/21/21
to Kill Bill users mailing-list
Hi,
I was trying to install KillBill on permise using Tomcat 8.5, JDK 1.8 and MYSQL. I have followed all the instruction provided in the documentation. KillBill is launching fine, but Kaui throwing the exceptions. I have also tried checking the code in eclipse IDE, it seems all Ruby related scripts are having errors.
2021-04-21T06:36:17,917+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-04-21T01:36:17.917000 #10796] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-04-21 01:36:17 -0500
2021-04-21T06:36:17,925+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-04-21T01:36:17.925000 #10796] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T06:36:17,940+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='7bf46437-85f2-420e-9abc-f74dd061602e', aId='', tId='', I, [2021-04-21T01:36:17.939000 #10796] INFO -- : Completed 500 Internal Server Error in 14ms
2021-04-21T06:36:17,943+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='7bf46437-85f2-420e-9abc-f74dd061602e', aId='', tId='', F, [2021-04-21T01:36:17.943000 #10796] FATAL -- :
2021-04-21T06:36:17,944+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='7bf46437-85f2-420e-9abc-f74dd061602e', aId='', tId='', F, [2021-04-21T01:36:17.944000 #10796] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T06:36:17,944+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='7bf46437-85f2-420e-9abc-f74dd061602e', aId='', tId='', F, [2021-04-21T01:36:17.944000 #10796] FATAL -- :
2021-04-21T06:36:17,945+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='7bf46437-85f2-420e-9abc-f74dd061602e', aId='', tId='', F, [2021-04-21T01:36:17.945000 #10796] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-04-21T06:53:21,235+0000 lvl='INFO', log='', th='http-nio-8080-exec-10', xff='', rId='', aId='', tId='', I, [2021-04-21T01:53:21.234000 #10796] INFO -- : Started GET "/kaui/" for 0:0:0:0:0:0:0:1 at 2021-04-21 01:53:21 -0500
2021-04-21T06:53:21,252+0000 lvl='INFO', log='', th='http-nio-8080-exec-10', xff='', rId='', aId='', tId='', I, [2021-04-21T01:53:21.251000 #10796] INFO -- : Processing by Kaui::HomeController#index as HTML
2021-04-21T06:53:21,261+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-10', xff='', rId='4f9168cf-a7a8-4025-8d1f-37fca6b63fd2', aId='', tId='', I, [2021-04-21T01:53:21.260000 #10796] INFO -- : Completed 401 Unauthorized in 7ms
2021-04-21T06:53:21,386+0000 lvl='INFO', log='', th='http-nio-8080-exec-9', xff='', rId='', aId='', tId='', I, [2021-04-21T01:53:21.386000 #10796] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-04-21 01:53:21 -0500
2021-04-21T06:53:21,391+0000 lvl='INFO', log='', th='http-nio-8080-exec-9', xff='', rId='', aId='', tId='', I, [2021-04-21T01:53:21.391000 #10796] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T06:53:21,405+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-9', xff='', rId='8b157887-b310-48ba-bf09-5b3dfc9f7ccb', aId='', tId='', I, [2021-04-21T01:53:21.405000 #10796] INFO -- : Completed 500 Internal Server Error in 14ms
2021-04-21T06:53:21,409+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-9', xff='', rId='8b157887-b310-48ba-bf09-5b3dfc9f7ccb', aId='', tId='', F, [2021-04-21T01:53:21.409000 #10796] FATAL -- :
2021-04-21T06:53:21,409+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-9', xff='', rId='8b157887-b310-48ba-bf09-5b3dfc9f7ccb', aId='', tId='', F, [2021-04-21T01:53:21.409000 #10796] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T06:53:21,410+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-9', xff='', rId='8b157887-b310-48ba-bf09-5b3dfc9f7ccb', aId='', tId='', F, [2021-04-21T01:53:21.409000 #10796] FATAL -- :
2021-04-21T06:53:21,410+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-9', xff='', rId='8b157887-b310-48ba-bf09-5b3dfc9f7ccb', aId='', tId='', F, [2021-04-21T01:53:21.410000 #10796] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-04-21T06:53:33,506+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-04-21T01:53:33.506000 #10796] INFO -- : Started GET "/kaui/users/sign_in" for 127.0.0.1 at 2021-04-21 01:53:33 -0500
2021-04-21T06:53:33,515+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-04-21T01:53:33.514000 #10796] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T06:53:33,527+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='94b85b26-12ff-4df7-9913-682cf08c1aae', aId='', tId='', I, [2021-04-21T01:53:33.527000 #10796] INFO -- : Completed 500 Internal Server Error in 11ms
2021-04-21T06:53:33,531+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='94b85b26-12ff-4df7-9913-682cf08c1aae', aId='', tId='', F, [2021-04-21T01:53:33.531000 #10796] FATAL -- :
2021-04-21T06:53:33,531+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='94b85b26-12ff-4df7-9913-682cf08c1aae', aId='', tId='', F, [2021-04-21T01:53:33.531000 #10796] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T06:53:33,532+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='94b85b26-12ff-4df7-9913-682cf08c1aae', aId='', tId='', F, [2021-04-21T01:53:33.531000 #10796] FATAL -- :
2021-04-21T06:53:33,532+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='94b85b26-12ff-4df7-9913-682cf08c1aae', aId='', tId='', F, [2021-04-21T01:53:33.532000 #10796] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-04-21T06:55:51,725+0000 lvl='INFO', log='', th='localhost-startStop-1', xff='', rId='', aId='', tId='', jruby 9.1.17.0 (2.3.3) 2018-04-20 d8b1ff9 Java HotSpot(TM) 64-Bit Server VM 25.291-b10 on 1.8.0_291-b10 +jit [mswin32-x86_64]
2021-04-21T06:55:51,741+0000 lvl='INFO', log='', th='localhost-startStop-1', xff='', rId='', aId='', tId='', using a shared (threadsafe!) runtime
2021-04-21T06:58:04,205+0000 lvl='INFO', log='', th='http-nio-8080-exec-6', xff='', rId='', aId='', tId='', I, [2021-04-21T01:58:04.190000 #2932] INFO -- : Started GET "/kaui/" for 0:0:0:0:0:0:0:1 at 2021-04-21 01:58:04 -0500
2021-04-21T06:58:04,430+0000 lvl='INFO', log='', th='http-nio-8080-exec-6', xff='', rId='', aId='', tId='', I, [2021-04-21T01:58:04.430000 #2932] INFO -- : Processing by Kaui::HomeController#index as HTML
2021-04-21T06:58:04,470+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-6', xff='', rId='bb156f68-a293-4b02-88e4-1911efcb7e14', aId='', tId='', I, [2021-04-21T01:58:04.470000 #2932] INFO -- : Completed 401 Unauthorized in 29ms
2021-04-21T06:58:04,774+0000 lvl='INFO', log='', th='http-nio-8080-exec-7', xff='', rId='', aId='', tId='', I, [2021-04-21T01:58:04.774000 #2932] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-04-21 01:58:04 -0500
2021-04-21T06:58:04,784+0000 lvl='INFO', log='', th='http-nio-8080-exec-7', xff='', rId='', aId='', tId='', I, [2021-04-21T01:58:04.784000 #2932] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T06:58:04,808+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-7', xff='', rId='c612ed12-58a2-48e8-93d1-9edce2874c5a', aId='', tId='', I, [2021-04-21T01:58:04.808000 #2932] INFO -- : Completed 500 Internal Server Error in 24ms
2021-04-21T06:58:04,819+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-7', xff='', rId='c612ed12-58a2-48e8-93d1-9edce2874c5a', aId='', tId='', F, [2021-04-21T01:58:04.819000 #2932] FATAL -- :
2021-04-21T06:58:04,820+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-7', xff='', rId='c612ed12-58a2-48e8-93d1-9edce2874c5a', aId='', tId='', F, [2021-04-21T01:58:04.819000 #2932] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T06:58:04,820+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-7', xff='', rId='c612ed12-58a2-48e8-93d1-9edce2874c5a', aId='', tId='', F, [2021-04-21T01:58:04.820000 #2932] FATAL -- :
2021-04-21T06:58:04,820+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-7', xff='', rId='c612ed12-58a2-48e8-93d1-9edce2874c5a', aId='', tId='', F, [2021-04-21T01:58:04.820000 #2932] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-04-21T14:07:49,333+0000 lvl='INFO', log='', th='http-nio-8080-exec-10', xff='', rId='', aId='', tId='', I, [2021-04-21T09:07:49.333000 #2932] INFO -- : Started GET "/kaui/" for 0:0:0:0:0:0:0:1 at 2021-04-21 09:07:49 -0500
2021-04-21T14:07:49,347+0000 lvl='INFO', log='', th='http-nio-8080-exec-10', xff='', rId='', aId='', tId='', I, [2021-04-21T09:07:49.346000 #2932] INFO -- : Processing by Kaui::HomeController#index as HTML
2021-04-21T14:07:49,351+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-10', xff='', rId='76a97e22-47b8-4a16-a543-b6bbf7409783', aId='', tId='', I, [2021-04-21T09:07:49.351000 #2932] INFO -- : Completed 401 Unauthorized in 3ms
2021-04-21T14:07:49,407+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-04-21T09:07:49.407000 #2932] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-04-21 09:07:49 -0500
2021-04-21T14:07:49,414+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-04-21T09:07:49.414000 #2932] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T14:07:49,439+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='bc7fa8ae-511c-48e6-be59-612bf670b4d4', aId='', tId='', I, [2021-04-21T09:07:49.438000 #2932] INFO -- : Completed 500 Internal Server Error in 23ms
2021-04-21T14:07:49,445+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='bc7fa8ae-511c-48e6-be59-612bf670b4d4', aId='', tId='', F, [2021-04-21T09:07:49.445000 #2932] FATAL -- :
2021-04-21T14:07:49,446+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='bc7fa8ae-511c-48e6-be59-612bf670b4d4', aId='', tId='', F, [2021-04-21T09:07:49.446000 #2932] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T14:07:49,446+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='bc7fa8ae-511c-48e6-be59-612bf670b4d4', aId='', tId='', F, [2021-04-21T09:07:49.446000 #2932] FATAL -- :
2021-04-21T14:07:49,447+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='bc7fa8ae-511c-48e6-be59-612bf670b4d4', aId='', tId='', F, [2021-04-21T09:07:49.447000 #2932] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-04-21T14:07:51,719+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-04-21T09:07:51.719000 #2932] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-04-21 09:07:51 -0500
2021-04-21T14:07:51,725+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-04-21T09:07:51.725000 #2932] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T14:07:51,737+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='f4ec2ae2-43ff-41f2-bd0a-e5735ea054dd', aId='', tId='', I, [2021-04-21T09:07:51.737000 #2932] INFO -- : Completed 500 Internal Server Error in 10ms
2021-04-21T14:07:51,741+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='f4ec2ae2-43ff-41f2-bd0a-e5735ea054dd', aId='', tId='', F, [2021-04-21T09:07:51.741000 #2932] FATAL -- :
2021-04-21T14:07:51,742+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='f4ec2ae2-43ff-41f2-bd0a-e5735ea054dd', aId='', tId='', F, [2021-04-21T09:07:51.742000 #2932] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T14:07:51,742+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='f4ec2ae2-43ff-41f2-bd0a-e5735ea054dd', aId='', tId='', F, [2021-04-21T09:07:51.742000 #2932] FATAL -- :
2021-04-21T14:07:51,743+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='f4ec2ae2-43ff-41f2-bd0a-e5735ea054dd', aId='', tId='', F, [2021-04-21T09:07:51.743000 #2932] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-04-21T14:56:02,366+0000 lvl='INFO', log='', th='localhost-startStop-1', xff='', rId='', aId='', tId='', jruby 9.1.17.0 (2.3.3) 2018-04-20 d8b1ff9 Java HotSpot(TM) 64-Bit Server VM 25.291-b10 on 1.8.0_291-b10 +jit [mswin32-x86_64]
2021-04-21T14:56:02,375+0000 lvl='INFO', log='', th='localhost-startStop-1', xff='', rId='', aId='', tId='', using a shared (threadsafe!) runtime
2021-04-21T14:58:14,614+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-04-21T09:58:14.600000 #164] INFO -- : Started GET "/kaui/" for 0:0:0:0:0:0:0:1 at 2021-04-21 09:58:14 -0500
2021-04-21T14:58:14,833+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-04-21T09:58:14.833000 #164] INFO -- : Processing by Kaui::HomeController#index as HTML
2021-04-21T14:58:14,943+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-3', xff='', rId='d6c46fc6-8c65-4ee7-9563-ab3834d83200', aId='', tId='', I, [2021-04-21T09:58:14.943000 #164] INFO -- : Completed 401 Unauthorized in 99ms
2021-04-21T14:58:15,928+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-04-21T09:58:15.927000 #164] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-04-21 09:58:15 -0500
2021-04-21T14:58:15,950+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-04-21T09:58:15.949000 #164] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T14:58:15,972+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='7b6ff759-10d0-4cb8-864e-7b213206fb69', aId='', tId='', I, [2021-04-21T09:58:15.971000 #164] INFO -- : Completed 500 Internal Server Error in 21ms
2021-04-21T14:58:15,986+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='7b6ff759-10d0-4cb8-864e-7b213206fb69', aId='', tId='', F, [2021-04-21T09:58:15.986000 #164] FATAL -- :
2021-04-21T14:58:15,987+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='7b6ff759-10d0-4cb8-864e-7b213206fb69', aId='', tId='', F, [2021-04-21T09:58:15.986000 #164] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T14:58:15,987+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='7b6ff759-10d0-4cb8-864e-7b213206fb69', aId='', tId='', F, [2021-04-21T09:58:15.987000 #164] FATAL -- :
2021-04-21T14:58:15,988+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='7b6ff759-10d0-4cb8-864e-7b213206fb69', aId='', tId='', F, [2021-04-21T09:58:15.987000 #164] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-04-21T17:58:57,904+0000 lvl='INFO', log='', th='localhost-startStop-1', xff='', rId='', aId='', tId='', jruby 9.1.17.0 (2.3.3) 2018-04-20 d8b1ff9 Java HotSpot(TM) 64-Bit Server VM 25.291-b10 on 1.8.0_291-b10 +jit [mswin32-x86_64]
2021-04-21T17:58:57,912+0000 lvl='INFO', log='', th='localhost-startStop-1', xff='', rId='', aId='', tId='', using a shared (threadsafe!) runtime
2021-04-21T19:12:35,108+0000 lvl='INFO', log='', th='http-nio-8080-exec-9', xff='', rId='', aId='', tId='', I, [2021-04-21T14:12:35.089000 #9064] INFO -- : Started GET "/kaui/" for 0:0:0:0:0:0:0:1 at 2021-04-21 14:12:35 -0500
2021-04-21T19:12:35,383+0000 lvl='INFO', log='', th='http-nio-8080-exec-9', xff='', rId='', aId='', tId='', I, [2021-04-21T14:12:35.383000 #9064] INFO -- : Processing by Kaui::HomeController#index as HTML
2021-04-21T19:12:35,501+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-9', xff='', rId='61bd18f3-70d1-434b-ab41-0956ad29544a', aId='', tId='', I, [2021-04-21T14:12:35.500000 #9064] INFO -- : Completed 401 Unauthorized in 106ms
2021-04-21T19:12:35,754+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-04-21T14:12:35.753000 #9064] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-04-21 14:12:35 -0500
2021-04-21T19:12:35,763+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-04-21T14:12:35.763000 #9064] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-04-21T19:12:35,790+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-3', xff='', rId='bb2d51c2-a6e0-47e2-9d6e-33fc059e24a6', aId='', tId='', I, [2021-04-21T14:12:35.790000 #9064] INFO -- : Completed 500 Internal Server Error in 27ms
2021-04-21T19:12:35,801+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-3', xff='', rId='bb2d51c2-a6e0-47e2-9d6e-33fc059e24a6', aId='', tId='', F, [2021-04-21T14:12:35.801000 #9064] FATAL -- :
2021-04-21T19:12:35,801+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-3', xff='', rId='bb2d51c2-a6e0-47e2-9d6e-33fc059e24a6', aId='', tId='', F, [2021-04-21T14:12:35.801000 #9064] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-04-21T19:12:35,802+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-3', xff='', rId='bb2d51c2-a6e0-47e2-9d6e-33fc059e24a6', aId='', tId='', F, [2021-04-21T14:12:35.802000 #9064] FATAL -- :
2021-04-21T19:12:35,802+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-3', xff='', rId='bb2d51c2-a6e0-47e2-9d6e-33fc059e24a6', aId='', tId='', F, [2021-04-21T14:12:35.802000 #9064] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:551:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/adapter.rb:35:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/jdbc/connection_methods.rb:14:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.1-java/lib/arjdbc/mysql/connection_methods.rb:91:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.7.1/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process'
gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
org/jruby/RubyKernel.java:1830:in `public_send'
gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve'
org/jruby/RubyArray.java:1735:in `each'
gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve'
gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call'
gems/gems/warden-1.2.8/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.8/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.1.2/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.1.2/lib/rack/head.rb:14:in `call'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:269:in `context'
gems/gems/rack-2.1.2/lib/rack/session/abstract/id.rb:263:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.1.2/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.1.2/lib/rack/runtime.rb:24:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.1.2/lib/rack/sendfile.rb:113:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in
1. Is there any prerequiste to install Ruby 2.4.2
2. The logs are so weird as after GET request, it throws 500 error.
Please advise
Pierre-Alexandre Meyer
Apr 22, 2021, 4:45:28 AM4/22/21
to Devi Kalaiselvan, Kill Bill users mailing-list
Hello,
It looks like the error "Public Key Retrieval is not allowed" is related to your database setup: https://stackoverflow.com/questions/50379839/connection-java-mysql-public-key-retrieval-is-not-allowed/53805393
Try updating the jdbc url per the post above (KAUI_CONFIG_DAO_URL env variable if you are using Docker), to see if it helps?
--
You received this message because you are subscribed to the Google Groups "Kill Bill users mailing-list" group.
To unsubscribe from this group and stop receiving emails from it, send an email to killbilling-us...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/killbilling-users/84d33c14-fcc3-480e-b7b1-dc46fd46e70fn%40googlegroups.com.
Pierre
Devi Kalaiselvan
Apr 22, 2021, 5:00:37 AM4/22/21
to Pierre-Alexandre Meyer, Kill Bill users mailing-list
I have tried these options, no luck. I don’t have the docker setup. Currently using the standalone war deployed in tomcat server as per the instructions provided in KB document.
Can you clarify is there are ruby installation required prior to solution run in tomcat. I do see lot of script errors when opening the war in eclipse. The problem still persists after adding all ruby jars to the war build path.
Thanks
Devi
Pierre-Alexandre Meyer
Apr 22, 2021, 8:41:52 AM4/22/21
to Devi Kalaiselvan, Kill Bill users mailing-list
Hi Devi,
If you are not using Docker, you can override the jdbc url using the System Property kaui.db.url.
I can also confirm there are no Ruby installation requirements.
--
Pierre
Kibet Elkanah
Jun 4, 2021, 3:49:49 AM6/4/21
to Kill Bill users mailing-list
Hi Pierre,
I am also experiencing the same problem as this.
Running kaui standalone version: 2.0.9 on apache-tomcat server 8.5.66
I have updated TOMCAT_HOME/conf/catalina.properties with the below
#kaui
kaui.url=http://127.0.0.1:8080
kaui.db.url=jdbc:mysql://127.0.0.1:3306/kaui?useSSL=false&allowPublicKeyRetrieval=true
kaui.db.username=killbill
kaui.db.password=killbill
My observations is that it appears the kaui standalone has been bundled with default db username (root) with no password - This is because i had to remove password for my root user.
It also appears that overriding from this file ( TOMCAT_HOME/conf/catalina.properties ) is not working for kaui. I expected the values configured in TOMCAT_HOME/conf/catalina.properties to be used.
Any idea how i can overcome this?
Regards,
Kibet
Kibet Elkanah
Jun 4, 2021, 4:29:44 AM6/4/21
to Kill Bill users mailing-list
Logs Related
2021-06-04T08:26:08,692+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-7', xff='', rId='', tok='', aRId='', tRId='', 2 * Server responded with a response on thread http-nio-8080-exec-7
2 < 200
2 < Content-Type: application/json
2021-06-04T08:26:18,862+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-06-04T11:26:18.862000 #8732] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-06-04 11:26:18 +0300
2021-06-04T08:26:18,886+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-06-04T11:26:18.886000 #8732] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-06-04T08:26:18,967+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='5eb1261b-3296-47e9-9d50-d2fd52c73f93', aId='', tId='', I, [2021-06-04T11:26:18.967000 #8732] INFO -- : Completed 500 Internal Server Error in 71ms
2021-06-04T08:26:18,989+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='5eb1261b-3296-47e9-9d50-d2fd52c73f93', aId='', tId='', F, [2021-06-04T11:26:18.989000 #8732] FATAL -- :
2021-06-04T08:26:18,996+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='5eb1261b-3296-47e9-9d50-d2fd52c73f93', aId='', tId='', F, [2021-06-04T11:26:18.995000 #8732] FATAL -- : ActiveRecord::JDBCError (Access denied for user 'root'@'localhost' (using password: YES)):
2021-06-04T08:26:18,999+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='5eb1261b-3296-47e9-9d50-d2fd52c73f93', aId='', tId='', F, [2021-06-04T11:26:18.999000 #8732] FATAL -- :
2021-06-04T08:26:19,002+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-5', xff='', rId='5eb1261b-3296-47e9-9d50-d2fd52c73f93', aId='', tId='', F, [2021-06-04T11:26:19.002000 #8732] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:557:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/mysql/adapter.rb:39:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/jdbc/connection_methods.rb:8:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/mysql/connection_methods.rb:99:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.8.0/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/warden-1.2.9/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.9/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.2.3/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.2.3/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.2.3/lib/rack/head.rb:12:in `call'
gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:266:in `context'
gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:260:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.2.3/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.2.3/lib/rack/runtime.rb:22:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.2.3/lib/rack/sendfile.rb:110:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
2021-06-04T08:26:21,988+0000 lvl='INFO', log='', th='http-nio-8080-exec-8', xff='', rId='', aId='', tId='', I, [2021-06-04T11:26:21.980000 #8732] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-06-04 11:26:21 +0300
2021-06-04T08:26:22,017+0000 lvl='INFO', log='', th='http-nio-8080-exec-8', xff='', rId='', aId='', tId='', I, [2021-06-04T11:26:22.016000 #8732] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-06-04T08:26:22,053+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-8', xff='', rId='cda95485-aba2-4954-a530-d49b55bb8430', aId='', tId='', I, [2021-06-04T11:26:22.052000 #8732] INFO -- : Completed 500 Internal Server Error in 28ms
2021-06-04T08:26:22,080+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-8', xff='', rId='cda95485-aba2-4954-a530-d49b55bb8430', aId='', tId='', F, [2021-06-04T11:26:22.078000 #8732] FATAL -- :
2021-06-04T08:26:22,100+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-8', xff='', rId='cda95485-aba2-4954-a530-d49b55bb8430', aId='', tId='', F, [2021-06-04T11:26:22.100000 #8732] FATAL -- : ActiveRecord::JDBCError (Access denied for user 'root'@'localhost' (using password: YES)):
2021-06-04T08:26:22,105+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-8', xff='', rId='cda95485-aba2-4954-a530-d49b55bb8430', aId='', tId='', F, [2021-06-04T11:26:22.103000 #8732] FATAL -- :
2021-06-04T08:26:22,112+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-8', xff='', rId='cda95485-aba2-4954-a530-d49b55bb8430', aId='', tId='', F, [2021-06-04T11:26:22.111000 #8732] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:557:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/abstract/core.rb:19:in `initialize'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/mysql/adapter.rb:39:in `initialize'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/jdbc/connection_methods.rb:8:in `jdbc_connection'
gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/mysql/connection_methods.rb:99:in `mysql_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection'
gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema'
gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types'
gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?'
gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new'
gems/gems/devise-4.8.0/app/controllers/devise/sessions_controller.rb:11:in `new'
gems/gems/warden-1.2.9/lib/warden/manager.rb:36:in `block in call'
org/jruby/RubyKernel.java:1138:in `catch'
gems/gems/warden-1.2.9/lib/warden/manager.rb:34:in `call'
gems/gems/rack-2.2.3/lib/rack/etag.rb:27:in `call'
gems/gems/rack-2.2.3/lib/rack/conditional_get.rb:27:in `call'
gems/gems/rack-2.2.3/lib/rack/head.rb:12:in `call'
gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:266:in `context'
gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:260:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged'
gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged'
gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call'
gems/gems/rack-2.2.3/lib/rack/method_override.rb:24:in `call'
gems/gems/rack-2.2.3/lib/rack/runtime.rb:22:in `call'
gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call'
gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call'
gems/gems/rack-2.2.3/lib/rack/sendfile.rb:110:in `call'
gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call'
uri:classloader:/rack/handler/servlet.rb:22:in `call'
Lalan Amaranto
Jul 6, 2021, 7:59:31 AM7/6/21
to Kill Bill users mailing-list
Hi,
Were you able to resolve the issue? What was the solution?
I also tried to deploy the KAUI WAR in Tomcat and encountered the same "Public Key Retrieval is not allowed".
I already updated the catalina.properties but it is still the same.
kaui.db.url=jdbc:mysql://<my ip>:3306/kaui?allowPublicKeyRetrieval=true
2021-07-06T11:47:37,243+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-07-06T19:47:37.243000 #10724] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-07-06 19:47:37 +0800
2021-07-06T11:47:37,250+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-07-06T19:47:37.250000 #10724] INFO -- : Processing by Kaui::SessionsController#new as HTML
2021-07-06T11:47:37,261+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='03d2d433-e92d-42f5-8d2a-ad10a17ef8d2', aId='', tId='', I, [2021-07-06T19:47:37.261000 #10724] INFO -- : Completed 500 Internal Server Error in 11ms
2021-07-06T11:47:37,269+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='03d2d433-e92d-42f5-8d2a-ad10a17ef8d2', aId='', tId='', F, [2021-07-06T19:47:37.268000 #10724] FATAL -- :
2021-07-06T11:47:37,269+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='03d2d433-e92d-42f5-8d2a-ad10a17ef8d2', aId='', tId='', F, [2021-07-06T19:47:37.269000 #10724] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed):
2021-07-06T11:47:37,270+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='03d2d433-e92d-42f5-8d2a-ad10a17ef8d2', aId='', tId='', F, [2021-07-06T19:47:37.270000 #10724] FATAL -- :
2021-07-06T11:47:37,270+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-4', xff='', rId='03d2d433-e92d-42f5-8d2a-ad10a17ef8d2', aId='', tId='', F, [2021-07-06T19:47:37.270000 #10724] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:557:in `initialize'
Thank you!
- Lan
Reshma Bidikar
Jul 6, 2021, 8:43:35 AM7/6/21
to Kill Bill users mailing-list
Hello,
Could you try specifying the database URL as
org.killbill.dao.url=jdbc:mysql://127.0.0.1:3306/killbill?allowPublicKeyRetrieval=true&useSSL=false as explained in the Getting Started FAQ?
Regards,
Reshma
Lalan Amaranto
Jul 6, 2021, 1:43:33 PM7/6/21
to Kill Bill users mailing-list
Hi Reshma,
Startup of the Tomcat server has no error.
KillBill is OK. "http://localhost:8080/index.html" is opening, I can browse thru different APIs and I can also test the APIs via Postman.
The "Public Key Retrieval is not allowed" appears when opening KAUI - http://localhost:8080/kaui/users/sign_in.
Here are the entries in "catalina.properties":
----------------------------------------------------------------------
org.killbill.dao.url=jdbc:mysql://127.0.0.1:3306/killbill?allowPublicKeyRetrieval=true&useSSL=false
org.killbill.dao.user=killbill
org.killbill.dao.password=killbill
org.killbill.billing.osgi.dao.url=jdbc:mysql://127.0.0.1:3306/killbill?allowPublicKeyRetrieval=true&useSSL=false
org.killbill.billing.osgi.dao.user=killbill
org.killbill.billing.osgi.dao.password=killbil
kaui.url=http://127.0.0.1:8080
kaui.db.url=jdbc:mysql://127.0.0.1:3306/kaui?allowPublicKeyRetrieval=true&useSSL=false
kaui.db.username=killbill
kaui.db.password=killbill
Below shows the users in MySql and tables for kaui:
------------------------------------------------------------------------------------
mysql> use kaui
Database changed
mysql> show tables;
+---------------------------+
| Tables_in_kaui |
+---------------------------+
| kaui_allowed_user_tenants |
| kaui_allowed_users |
| kaui_tenants |
| kaui_users |
+---------------------------+
4 rows in set (0.00 sec)
mysql> select user from mysql.user;
+------------------+
| user |
+------------------+
| killbill |
| killbilluser |
| lan |
| mysql.infoschema |
| mysql.session |
| mysql.sys |
| root |
+------------------+
7 rows in set (0.00 sec)
Regards,
Lan
Reshma Bidikar
Jul 6, 2021, 11:48:26 PM7/6/21
to Kill Bill users mailing-list
Hello,
I suspect that the database URL from the config file is not used by Kaui. Could you try deleting it from the config file and adding it back in?
If the issue still occurs, could you please send the complete stacktrace?
Regards,
Reshma
Lalan Amaranto
Jul 7, 2021, 2:37:01 PM7/7/21
to Kill Bill users mailing-list
Hi Reshma,
Getting a hint from your suspicion, I tried changing the IP address to an address that is not available from my machine.
When I did it on KillBill, the logs showed error connecting to the IP address that I have given. With this result, I am sure that KillBill is using the values in catalina.properties.
Next, I corrected the IP address for KillBill and changed the IP address for KAUI to a non existent address.
This did not result to any error in the logs about the IP address. KAUI might not be really using the catalina values.
Attached is the logs with both entries for KillBill and KAUI are correct. The last entries on the logs was when I tried to open KAUI.
Regards,
Lan
Reshma Bidikar
Jul 7, 2021, 11:45:11 PM7/7/21
to Kill Bill users mailing-list
Hello Lan,
I have observed that sometimes when the Kaui related properties are copied from the Kill Bill docs, they seem to be ignored. Would request you to delete all the Kaui related properties from your config file and type them out (not copy) by referring to the docs and see if it helps.
Regards,
Reshma
Lalan Amaranto
Jul 9, 2021, 9:47:35 AM7/9/21
to Kill Bill users mailing-list
Hi Reshma,
I transferred to another machine and KAUI is now working. I am still not sure why it is not reading the KAUI config in the previous machine. I will revisit it in the future.
I am now in the "Setting up KPM in Kaui". The purpose is to have the ability to install plugin via Kaui UI.
However, I encountered the error "Duplicate import: org.joda.time.format". I searched in the email group and found 2 items somewhat related to it.
It seems like support with Ruby plugin is discontinued. Am I correct to assume that this section of tutorial will no longer work?
Respectfully,
Lan
Reshma Bidikar
Jul 12, 2021, 12:05:27 AM7/12/21
to Kill Bill users mailing-list
Hello Lan,
Setting up KPM in Kaui does work. Are you on a Windows system? If so, could you try deleting
<kpm_bundles_path>/platform/killbill-platform-osgi-bundles-jruby-0.40.4.jar as explained in the FAQ section?
Regards,
Reshma
Lalan Amaranto
Jul 12, 2021, 5:59:30 AM7/12/21
to Kill Bill users mailing-list
Thank you Reshma.
I was able to access the KPM via KAUI and I installed stripe by clicking the install icon in Plugin Screen.
A notification saying "Operation Complete, Reload Page" appeared but the Stripe Plugin was still not listed in the installed plugins.
During the process, the stripe-plugin-7.3.0.jar was copied here: c:\var\temp\bundles\plugins\java\stripe-plugin\7.3.0
I am attaching the logs. The first error happened at line 1263.
I am assuming that it could be an issue with the folder in drive C. How can I change the default path for the JAR?
I also changed the default path for OSGI cache area because of a folder issue. I used the org.killbill.osgi.root.dir in catalina.properties.
regards,
Lan
Reshma Bidikar
Jul 12, 2021, 7:24:11 AM7/12/21
to Kill Bill users mailing-list
Hello Lan,
This is a known issue in Windows and we are still working on fixing it. In the meantime, could you check if the JAR file for the stripe plugin is copied to your plugin path? If so, could you stop and start Tomcat again, open the kpm page and check if the stripe plugin appears there?
Hope this helps!
Regards,
Reshma
Lalan Amaranto
Jul 12, 2021, 8:58:04 AM7/12/21
to Kill Bill users mailing-list
Hi Reshma,
The JAR file is present and Stripe Plugin is shown in the KAUI UI.
However, it is on "STOPPED" status. There was a null pointer error in the logs on the initial run.
It tried run it again by clicking the Start icon... below is the latest error.
java.lang.IllegalStateException: Plugin org.kill-bill.billing.plugin.java.stripe-plugin version null cannot be started because the version null already exists in the registry (state = 4)
at org.killbill.billing.osgi.BundleRegistry.installAndStartNewBundle(BundleRegistry.java:75)
at org.killbill.billing.osgi.OSGIListener.handleKillbillEvent(OSGIListener.java:111)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.google.common.eventbus.Subscriber.invokeSubscriberMethod(Subscriber.java:87)
at com.google.common.eventbus.Subscriber$1.run(Subscriber.java:72)
at com.google.common.util.concurrent.DirectExecutor.execute(DirectExecutor.java:30)
at com.google.common.eventbus.Subscriber.dispatchEvent(Subscriber.java:67)
at com.google.common.eventbus.Dispatcher$ImmediateDispatcher.dispatch(Dispatcher.java:186)
at com.google.common.eventbus.EventBusThatThrowsException.postWithException(EventBusThatThrowsException.java:68)
at org.killbill.bus.DefaultPersistentBus.dispatchBusEventWithMetrics(DefaultPersistentBus.java:395)
at org.killbill.bus.dispatching.BusCallableCallback.dispatch(BusCallableCallback.java:43)
at org.killbill.bus.dispatching.BusCallableCallback.dispatch(BusCallableCallback.java:32)
at org.killbill.queue.dispatching.Dispatcher$CallableQueueHandler.call(Dispatcher.java:142)
at org.killbill.queue.dispatching.Dispatcher$CallableQueueHandler.call(Dispatcher.java:110)
at org.killbill.commons.concurrent.WrappedCallable.call(WrappedCallable.java:45)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.killbill.commons.concurrent.WrappedRunnable.run(WrappedRunnable.java:49)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Regards,
Lan
Reshma Bidikar
Jul 13, 2021, 4:17:24 AM7/13/21
to Kill Bill users mailing-list
Hello Lan,
This error mostly occurs when the plugin fails to start for some reason the first time. This is a known issue and is being tracked here. One reason why the plugin failed to start the first time could be that the Stripe-related database tables are not created as explained in the Stripe Plugin Readme Requirements section. Could you create the tables if they were not created earlier? Once the tables are created, you will also need to restart Tomcat. Hope this helps!
Regards,
Reshma
Lalan Amaranto
Jul 13, 2021, 5:58:43 AM7/13/21
to Kill Bill users mailing-list
Hi Reshma,
You were right that the Stripe related tables were not created so I ran the DDL to create the DB objects.
I restarted with the plugin installation but the result is still the same.
The exception logs that I shared previously is for when trying to start the plugin from KAUI (clicking the start icon).
Here is the error when Tomcat is started. There is a null pointer at "setupTmpDir".
2021-07-13T09:29:13,484+0000 lvl='INFO', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Starting bundle file:D:\KillBill Files\KPM Bundles\platform\stripe-plugin-7.3.0.jar
2021-07-13T09:29:13,484+0000 lvl='INFO', log='KillbillLogWriter', th='FelixDispatchQueue', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.killbill-platform-osgi-bundles-logger] BundleEvent STARTED
2021-07-13T09:29:13,500+0000 lvl='INFO', log='KillbillLogWriter', th='FelixDispatchQueue', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.plugin.java.stripe-plugin] BundleEvent RESOLVED
2021-07-13T09:29:13,500+0000 lvl='INFO', log='KillbillActivatorBase', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', OSGI bundle='org.kill-bill.billing.plugin.java.stripe-plugin' received START command
2021-07-13T09:29:13,500+0000 lvl='INFO', log='KillbillLogWriter', th='FelixDispatchQueue', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.plugin.java.stripe-plugin] BundleEvent STOPPED
2021-07-13T09:29:13,515+0000 lvl='WARN', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Unable to start bundle
org.osgi.framework.BundleException: Activator start error in bundle org.kill-bill.billing.plugin.java.stripe-plugin [3].
at org.apache.felix.framework.Felix.activateBundle(Felix.java:2204)
at org.apache.felix.framework.Felix.startBundle(Felix.java:2072)
at org.apache.felix.framework.BundleImpl.start(BundleImpl.java:976)
at org.apache.felix.framework.BundleImpl.start(BundleImpl.java:963)
at org.killbill.billing.osgi.FileInstall.startBundle(FileInstall.java:259)
at org.killbill.billing.osgi.BundleRegistry.startBundles(BundleRegistry.java:106)
at org.killbill.billing.osgi.DefaultOSGIService.start(DefaultOSGIService.java:105)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.killbill.billing.lifecycle.DefaultLifecycle.doFireStage(DefaultLifecycle.java:154)
at org.killbill.billing.lifecycle.DefaultLifecycle.fireSequence(DefaultLifecycle.java:141)
at org.killbill.billing.lifecycle.DefaultLifecycle.fireStartupSequencePriorEventRegistration(DefaultLifecycle.java:82)
at org.killbill.billing.server.listeners.KillbillPlatformGuiceListener.startLifecycle(KillbillPlatformGuiceListener.java:299)
at org.killbill.billing.server.listeners.KillbillPlatformGuiceListener.contextInitialized(KillbillPlatformGuiceListener.java:129)
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4705)
at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5168)
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:743)
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:719)
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705)
at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1015)
at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1895)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.NullPointerException: null
at org.killbill.billing.osgi.libs.killbill.KillbillActivatorBase.setupTmpDir(KillbillActivatorBase.java:225)
at org.killbill.billing.osgi.libs.killbill.KillbillActivatorBase.start(KillbillActivatorBase.java:84)
at org.killbill.billing.plugin.stripe.StripeActivator.start(StripeActivator.java:46)
at org.apache.felix.framework.util.SecureAction.startActivator(SecureAction.java:645)
at org.apache.felix.framework.Felix.activateBundle(Felix.java:2154)
... 28 common frames omitted
Reshma Bidikar
Jul 13, 2021, 9:31:45 AM7/13/21
to Kill Bill users mailing-list
Hello Lan,
Sorry, but I'm not really sure I follow. Could you please try the following:
1) Stop Tomcat
2) Delete the stripe-plugin directory from your plugin path (/var/tmp/bundles/java or whatever directory you have specified)
3) Start Tomcat
4) Install the plugin again via Kaui
5) Confirm that the plugin is present in your plugin path (/var/tmp/bundles/java or whatever directory you have specified)
6) Restart Tomcat
7) Open the kpm page in Kaui and see if the stripe plugin is visible
If you still cannot see the stripe-plugin after step 6, can you please send the Kill Bill logs?
Regards,
Reshma
Lalan Amaranto
Jul 14, 2021, 5:14:14 AM7/14/21
to Kill Bill users mailing-list
Hi Reshma,
Thank you for patiently attending to my inquiries. I will put the details below:
I will start with the details that I used in "Setting up KPM in Kaui"
* I used "D:/KillBill Files/KPM Bundles" as <kpm_bundles_path> when I issued the command "kpm pull_defaultbundles"
* My catalina entry for the bundles are:
org.killbill.osgi.bundle.install.dir=D:/KillBill Files/KPM Bundles
* Both the default bundles are stored in the above folder and both are also running and shown in KAUI KPM screen.
killbill-platform-osgi-bundles-kpm-0.40.7.jar
killbill-platform-osgi-bundles-logger-0.40.7.jar
Now I will proceed with the steps that you provided.
1) Stop Tomcat
2) Delete the stripe-plugin directory from your plugin path (/var/tmp/bundles/java or whatever directory you have specified)
3) Start Tomcat
4) Install the plugin again via Kaui
See attached "logs during install.txt"
5) Confirm that the plugin is present in your plugin path (/var/tmp/bundles/java or whatever directory you have specified)
6) Restart Tomcat
See attached "logs after tomcat restart.txt"
7) Open the kpm page in Kaui and see if the stripe plugin is visible
Stripe plugin is not visible in KPM screen.
Now, this is what I did so far:
1. I copied the stripe JAR to the bundle folder where the default bundles are.
2. When Tomcat is restarted, Stripe appears on the list onscreen.
3. I tried to start by clicking the start button (triangle icon) then the notification "Operation Complete, Reload Page" appeared.
See attached "logs after start of plugin.txt"
4. I also tried to update the sha1.yml but I am not sure what the repository for the plugin should be so I left it unchanged. Please see below:
---
sha1:
org.kill-bill.billing:killbill-platform-osgi-bundles-defaultbundles:tar.gz:0.40.7: 507f45090bfc45059427ff4b6313730f9396c047
killbill:
0.22.24:
killbill: 0.22.24
killbill-oss-parent: 0.144.51
killbill-api: 0.53.17
killbill-plugin-api: 0.26.4
killbill-commons: 0.24.14
killbill-platform: 0.40.7
nexus:
org.kill-bill.billing:killbill-platform-osgi-bundles-defaultbundles:tar.gz:0.40.7:
:sha1: 507f45090bfc45059427ff4b6313730f9396c047
:version: 0.40.7
:repository_path: "/org/kill-bill/billing/killbill-platform-osgi-bundles-defaultbundles/0.40.7/killbill-platform-osgi-bundles-defaultbundles-0.40.7.tar.gz"
:is_tgz: true
---
sha1:
org.kill-bill.billing.plugin.java:stripe-plugin:jar:7.3.0: 8683ec20a6842aaf4629d540074eb5a941b89e79
killbill:
0.22.24:
killbill: 0.22.24
killbill-oss-parent: 0.144.51
killbill-api: 0.53.17
killbill-plugin-api: 0.26.4
killbill-commons: 0.24.14
killbill-platform: 0.40.7
nexus:
org.kill-bill.billing.plugin.java:stripe-plugin:jar:7.3.0:
:sha1: 8683ec20a6842aaf4629d540074eb5a941b89e79
:version: 7.3.0
:repository_path: "/org/kill-bill/billing/plugin/java/stripe-plugin/7.3.0/stripe-plugin-7.3.0.jar"
:is_tgz: false
Regards,
Lan
Reshma Bidikar
Jul 15, 2021, 12:27:23 AM7/15/21
to Kill Bill users mailing-list
Hello Lan,
As per your message, "I copied the stripe JAR to the bundle folder where the default bundles are.", could you please elaborate on this? Where have you copied the stripe JAR file? Also, just to confirm, you have created the database tables for the stripe plugin. Is that correct?
Regards,
Reshma
Lalan Amaranto
Jul 15, 2021, 12:54:59 AM7/15/21
to Kill Bill users mailing-list
Hi Reshma,
Yes, I already created the database tables for Stripe plugin.
About the JAR that I copied in the "Now, this is what I did so far:" section of my email:
During the plugin installation, the stripe JAR file was created in "/var/tmp/bundles/java/stripe-plugin/7/3/0". I included the screenshot for this in the previous email.
However, it did not appear in the KAUI - KPM screen even after restart of Tomcat. So, I tried to find out how to fix it.
The 2 default bundles below are show on the screen. So, I thought that I should try to manually copy the stripe JAR to the same location - which I did.
killbill-platform-osgi-bundles-kpm-0.40.7.jar
killbill-platform-osgi-bundles-logger-0.40.7.jar
The stripe plugin appeared in the KPM Plugin screen after this. However, it does not run. I included the logs in the previous email for reference.
Thank you again for you continued support.
Regards,
Lan
Reshma Bidikar
Jul 15, 2021, 9:04:51 AM7/15/21
to Kill Bill users mailing-list
Hello Lan,
You do not need to copy the Stripe Jar file to the path of the default bundles, it needs to be present in the /var/tmp/bundles/java/stripe-plugin/
After restarting Tomcat, the plugin should show up in Kaui, your logs after restarting Tomcat do not show any errors.
Could you try the following:
1. Open
localhost:8080/1.0/healthcheck?pretty=true and see if "Stripe OK" message is displayed?
2. Execute steps 1 and 2 as explained in Using Stripe Checkout and see if you 201 response code with a success_url in the body?
Regards,
Reshma
Reshma Bidikar
Jul 16, 2021, 4:08:18 AM7/16/21
to Kill Bill users mailing-list
Hello,
We have just fixed some kpm issues on Windows. Could you please install kpm again and give it a try? It would be better if you delete the existing Stripe Jar file from your /var/tmp/bundles path and restart Tomcat before you give it a go. Hope this helps!
Regards,
Reshma
Lalan Amaranto
Jul 16, 2021, 7:21:18 AM7/16/21
to Kill Bill users mailing-list
Hi Reshma,
Here are the steps that I've done:
1. Deleted Stripe JAR from "/var/tmp/bundles"
2. Reinstalled KPM via Ruby
3. Started Tomcat
4. Installed Stripe via KAUI KPM screen
5. Checked and found the Stripe JAR file in "C:\var\tmp\bundles\plugins\java\stripe-plugin\7.3.0"
Note: New items found after installation (previous installations doesn't have these)
a) new subfolder was created "C:\var\tmp\bundles\plugins\java\stripe-plugin\SET_DEFAULT"
b) new subfolder was created "C:\var\tmp\bundles\plugins\java\stripe-plugin\7.3.0\tmp"
c) new file was created "C:\var\tmp\bundles\plugins\plugin_identifiers.json"
6. Restarted Tomcat
7. Stripe Plugin is still not available in KAUI KPM screen
8. I opened the "localhost:8080/1.0/healthcheck?pretty=true" from your previous email. I pasted the JSON result below:
{
"com.codahale.metrics.health.jvm.ThreadDeadlockHealthCheck" : {
"healthy" : true
},
"main.pool.Connection99Percent" : {
"healthy" : true
},
"main.pool.ConnectivityCheck" : {
"healthy" : true
},
"org.killbill.billing.server.healthchecks.KillbillHealthcheck" : {
"healthy" : true,
"message" : "OK"
},
"org.killbill.billing.server.healthchecks.KillbillPluginsHealthcheck" : {
"healthy" : true
},
"org.killbill.billing.server.healthchecks.KillbillQueuesHealthcheck" : {
"healthy" : true,
"bus" : {
"growing" : false
},
"overdue-service:overdue-check-queue" : {
"growing" : false
},
"entitlement-service:entitlement-events" : {
"growing" : false
},
"invoice-service:next-billing-date-queue" : {
"growing" : false
},
"notifications-retries:extBusEvent-listener" : {
"growing" : false
},
"payment-service:janitor" : {
"growing" : false
},
"externalBus" : {
"growing" : false
},
"payment-service:retry" : {
"growing" : false
},
"notifications-retries:next-billing-date-queue" : {
"growing" : false
},
"subscription-service:subscription-events" : {
"growing" : false
},
"invoice-service:parent-invoice-commitment-queue" : {
"growing" : false
},
"server-service:push-notification-queue" : {
"growing" : false
},
"overdue-service:overdue-async-bus-queue" : {
"growing" : false
},
"notifications-retries:invoice-listener" : {
"growing" : false
}
},
"osgi.pool.Connection99Percent" : {
"healthy" : true
},
"osgi.pool.ConnectivityCheck" : {
"healthy" : true
},
"shiro.pool.Connection99Percent" : {
"healthy" : true
},
"shiro.pool.ConnectivityCheck" : {
"healthy" : true
}
}
Regards,
Lan
Reshma Bidikar
Jul 16, 2021, 10:23:54 AM7/16/21
to Kill Bill users mailing-list
Hello,
This is indeed strange. Could you please send us the complete Kill Bill and Tomcat logs for the above? Also, could you try installing some other plugin (like the email notification plugin) and see if it works for you?
Regards,
Reshma
Lalan Amaranto
Jul 16, 2021, 5:05:31 PM7/16/21
to Kill Bill users mailing-list
Hi Reshma,
Finally, I was able to install the Stripe plugin.
Steps Taken:
1. Installed Stripe Plugin via the KAUI -KPM screen (see attached Logs_Install.txt for the logs)
2. The JAR was created in "C:\var\tmp\bundles\plugins\java\stripe-plugin\7.3.0" but the Stripe was not shown onscreen even after refresh so I stopped the Tomcat
3. I copied the whole folder from "java" to the KPM_Bundle_Path that I used in the instruction below :
4. Restarted the Tomcat. (see Logs_AfterCopy.txt for the logs)
5. I opened the KAUI-KPM and the Stripe Plugin is shown and is running.
Thank you very much for your help.
Respectfully,
Lan
Reshma Bidikar
Jul 19, 2021, 11:44:22 PM7/19/21
to Kill Bill users mailing-list
Hello,
Good to know that you've got the plugin working finally! We did figure out the issue as well, you need to set an additional property (org.killbill.billing.plugin.kpm.bundlesPath) in the Kill Bill configuration file. We have now updated the Getting Started Document to reflect this.
Regards,
Reshma
Reply all
Reply to author
Forward
0 new messages