web_1 | {"ts":"2023-01-10T15:20:38.777","unix_ts":1673364038.7777214,"severity":"DEBUG","pid":147,"message":" (0.4ms) BEGIN"}
web_1 | {"ts":"2023-01-10T15:20:38.782","unix_ts":1673364038.7825606,"severity":"DEBUG","pid":147,"message":" LtiProvider::Launch Create (2.0ms) INSERT INTO \"lti_provider_launches\" (\"canvas_url\", \"nonce\", \"provider_params\", \"created_at\", \"updated_at\") VALUES ($1, $2, $3, $4, $5) RETURNING \"id\" [[\"canvas_url\", \"http://canvas-dev.example.org\"], [\"nonce\", \"8LqH29odkR4****************iz9Me3s\"], [\"provider_params\", \"---\\ncontext_id: 4dde05e8c***************548820eee93a3\\ncontext_label: Testing\\ncontext_title: Testing Course\\nlaunch_presentation_document_target: iframe\\nlaunch_presentation_height: '400'\\nlaunch_presentation_locale: en\\nlaunch_presentation_return_url: http://canvas-dev.example.org/courses/1/external_content/success/external_tool_redirect\\nlaunch_presentation_width: '800'\\nlis_person_contact_email_primary: [email protected]\\nlis_person_name_family: ''\\nlis_person_name_full: [email protected]\\nlis_person_name_given: [email protected]\\nlti_message_type: basic-lti-launch-request\\nlti_version: LTI-1p0\\noauth_callback: about:blank\\noauth_consumer_key: Tk*******************C\\noauth_nonce: 8LqH29odkR4****************iz9Me3s\\noauth_signature: frYDclV3dynlr+WMu6TEwp8XHo4=\\noauth_signature_method: HMAC-SHA1\\noauth_timestamp: '1673364035'\\noauth_version: '1.0'\\nresource_link_id: 4dde05e8c***************548820eee93a3\\nresource_link_title: Roll Call\\nroles: Instructor,urn:lti:instrole:ims/lis/Administrator,urn:lti:sysrole:ims/lis/SysAdmin\\ntool_consumer_info_product_family_code: canvas\\ntool_consumer_info_version: cloud\\ntool_consumer_instance_contact_email: [email protected]\\ntool_consumer_instance_guid: t5yCo*****************kk:canvas-lms\\ntool_consumer_instance_name: Stiegler EdTech\\nuser_id: 535fa08**************c062838\\nuser_image: http://canvas.instructure.com/images/messages/avatar-50.png\\ncustom_canvas_api_domain: canvas.docker\\ncustom_canvas_course_id: '1'\\ncustom_canvas_enrollment_state: active\\ncustom_canvas_user_id: '1'\\ncustom_canvas_user_login_id: [email protected]\\ncustom_canvas_workflow_state: available\\next_roles: urn:lti:instrole:ims/lis/Administrator,urn:lti:instrole:ims/lis/Instructor,urn:lti:role:ims/lis/Instructor,urn:lti:sysrole:ims/lis/SysAdmin,urn:lti:sysrole:ims/lis/User\\n\"], [\"created_at\", \"2023-01-10 15:20:38.775806\"], [\"updated_at\", \"2023-01-10 15:20:38.775806\"]]"}
web_1 | {"ts":"2023-01-10T15:20:38.786","unix_ts":1673364038.7867649,"severity":"DEBUG","pid":147,"message":" (2.0ms) COMMIT"}
web_1 | {"ts":"2023-01-10T15:20:38.789","unix_ts":1673364038.789574,"severity":"INFO","pid":147,"method":"POST","controller":"LtiProvider::LtiController","action":"launch","format":"html","path":"/launch","request_id":"615bf35cc8bd3a465ebe13a27ba8e461","ip":"128.61.61.221","status":302,"db_runtime":14.695323072373867,"params":{"oauth_consumer_key":"Tk*******************C","oauth_signature_method":"HMAC-SHA1","oauth_timestamp":"1673364035","oauth_nonce":"8LqH29odkR4****************iz9Me3s","oauth_version":"1.0","context_id":"4dde05e8c***************548820eee93a3","context_label":"Testing","context_title":"Testing Course","custom_canvas_api_domain":"canvas.docker","custom_canvas_course_id":"1","custom_canvas_enrollment_state":"active","custom_canvas_user_id":"1","custom_canvas_user_login_id":"[email protected]","custom_canvas_workflow_state":"available","ext_roles":"urn:lti:instrole:ims/lis/Administrator,urn:lti:instrole:ims/lis/Instructor,urn:lti:role:ims/lis/Instructor,urn:lti:sysrole:ims/lis/SysAdmin,urn:lti:sysrole:ims/lis/User","launch_presentation_document_target":"iframe","launch_presentation_height":"400","launch_presentation_locale":"en","launch_presentation_return_url":"http://canvas-dev.example.org/courses/1/external_content/success/external_tool_redirect","launch_presentation_width":"800","lis_person_contact_email_primary":"[email protected]","lis_person_name_family":"","lis_person_name_full":"[email protected]","lis_person_name_given":"[email protected]","lti_message_type":"basic-lti-launch-request","lti_version":"LTI-1p0","oauth_callback":"about:blank","resource_link_id":"4dde05e8c***************548820eee93a3","resource_link_title":"Roll Call","roles":"Instructor,urn:lti:instrole:ims/lis/Administrator,urn:lti:sysrole:ims/lis/SysAdmin","tool_consumer_info_product_family_code":"canvas","tool_consumer_info_version":"cloud","tool_consumer_instance_contact_email":"[email protected]","tool_consumer_instance_guid":"t5yCo*****************kk:canvas-lms","tool_consumer_instance_name":"exampleOrg EdTech","user_id":"535fa08**************c062838","user_image":"http://canvas.instructure.com/images/messages/avatar-50.png","oauth_signature":"frYDclV3dynlr+WMu6TEwp8XHo4="},"redirect_location":"https://rollcall.canvas-dev.example.org/cookie_test?nonce=8LqH29odkR4****************iz9Me3s"}
web_1 | 192.168.128.5 - - [10/Jan/2023:15:20:38 +0000] "POST /launch HTTP/1.1" 302 184 "http://canvas-dev.example.org/courses/1/external_tools/4" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:108.0) Gecko/20100101 Firefox/108.0"
web_1 | {"ts":"2023-01-10T15:20:38.872","unix_ts":1673364038.8729892,"severity":"DEBUG","pid":147,"message":" LtiProvider::Launch Load (1.9ms) SELECT \"lti_provider_launches\".* FROM \"lti_provider_launches\" WHERE (created_at > '2023-01-10 15:15:38.868125') AND \"lti_provider_launches\".\"nonce\" = $1 LIMIT $2 [[\"nonce\", \"8LqH29odkR4****************iz9Me3s\"], [\"LIMIT\", 1]]"}
web_1 | {"ts":"2023-01-10T15:20:38.876","unix_ts":1673364038.8760638,"severity":"DEBUG","pid":147,"message":" (0.4ms) BEGIN"}
web_1 | {"ts":"2023-01-10T15:20:38.877","unix_ts":1673364038.8776205,"severity":"DEBUG","pid":147,"message":" LtiProvider::Launch Destroy (1.2ms) DELETE FROM \"lti_provider_launches\" WHERE \"lti_provider_launches\".\"id\" = $1 [[\"id\", 23]]"}
web_1 | {"ts":"2023-01-10T15:20:38.879","unix_ts":1673364038.8792374,"severity":"DEBUG","pid":147,"message":" (1.3ms) COMMIT"}
web_1 | {"ts":"2023-01-10T15:20:38.879","unix_ts":1673364038.8799865,"severity":"INFO","pid":147,"method":"GET","controller":"LtiProvider::LtiController","action":"cookie_test","format":"html","path":"/cookie_test?nonce=8LqH29odkR4****************iz9Me3s","request_id":"8e3e9dc56aa0e3da3287a40aa798028c","ip":"128.61.61.221","status":302,"db_runtime":4.876868799328804,"params":{"nonce":"8LqH29odkR4****************iz9Me3s"},"redirect_location":"https://rollcall.canvas-dev.example.org/"}
web_1 | 192.168.128.5 - - [10/Jan/2023:15:20:38 +0000] "GET /cookie_test?nonce=8LqH29odkR4****************iz9Me3s HTTP/1.1" 302 124 "http://canvas-dev.example.org/courses/1/external_tools/4" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:108.0) Gecko/20100101 Firefox/108.0"
web_1 | {"ts":"2023-01-10T15:20:38.931","unix_ts":1673364038.9313533,"severity":"DEBUG","pid":147,"message":" CanvasOauth::Authorization Load (1.9ms) SELECT \"canvas_oauth_authorizations\".* FROM \"canvas_oauth_authorizations\" WHERE \"canvas_oauth_authorizations\".\"canvas_user_id\" = $1 AND \"canvas_oauth_authorizations\".\"tool_consumer_instance_guid\" = $2 ORDER BY created_at DESC LIMIT $3 [[\"canvas_user_id\", 1], [\"tool_consumer_instance_guid\", \"t5yCo*****************kk:canvas-lms\"], [\"LIMIT\", 1]]"}
web_1 | {"ts":"2023-01-10T15:20:38.934","unix_ts":1673364038.9340544,"severity":"DEBUG","pid":147,"message":" (0.4ms) BEGIN"}
web_1 | {"ts":"2023-01-10T15:20:38.935","unix_ts":1673364038.9354925,"severity":"DEBUG","pid":147,"message":" CanvasOauth::Authorization Update (0.7ms) UPDATE \"canvas_oauth_authorizations\" SET \"last_used_at\" = $1, \"updated_at\" = $2 WHERE \"canvas_oauth_authorizations\".\"id\" = $3 [[\"last_used_at\", \"2023-01-10 15:20:38.932117\"], [\"updated_at\", \"2023-01-10 15:20:38.932924\"], [\"id\", 1]]"}
web_1 | {"ts":"2023-01-10T15:20:38.937","unix_ts":1673364038.937365,"severity":"DEBUG","pid":147,"message":" (1.3ms) COMMIT"}
web_1 | {"ts":"2023-01-10T15:20:38.939","unix_ts":1673364038.9394524,"severity":"INFO","pid":147,"method":"GET","controller":"HomeController","action":"index","format":"html","path":"/","request_id":"2b563ce27fc0c247dcc6469c51c9cd61","ip":"128.61.61.221","status":302,"db_runtime":4.332938231527805,"params":{},"redirect_location":"https://rollcall.canvas-dev.example.org/courses/1"}
web_1 | 192.168.128.5 - - [10/Jan/2023:15:20:38 +0000] "GET / HTTP/1.1" 302 133 "http://canvas-dev.example.org/courses/1/external_tools/4" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:108.0) Gecko/20100101 Firefox/108.0"
web_1 | {"ts":"2023-01-10T15:20:39.052","unix_ts":1673364039.052046,"severity":"DEBUG","pid":147,"message":" CanvasOauth::Authorization Load (0.7ms) SELECT \"canvas_oauth_authorizations\".* FROM \"canvas_oauth_authorizations\" WHERE \"canvas_oauth_authorizations\".\"canvas_user_id\" = $1 AND \"canvas_oauth_authorizations\".\"tool_consumer_instance_guid\" = $2 ORDER BY created_at DESC LIMIT $3 [[\"canvas_user_id\", 1], [\"tool_consumer_instance_guid\", \"t5yCo*****************kk:canvas-lms\"], [\"LIMIT\", 1]]"}
web_1 | {"ts":"2023-01-10T15:20:39.055","unix_ts":1673364039.0551834,"severity":"DEBUG","pid":147,"message":" (0.5ms) BEGIN"}
web_1 | {"ts":"2023-01-10T15:20:39.056","unix_ts":1673364039.0562527,"severity":"DEBUG","pid":147,"message":" CanvasOauth::Authorization Update (0.7ms) UPDATE \"canvas_oauth_authorizations\" SET \"last_used_at\" = $1, \"updated_at\" = $2 WHERE \"canvas_oauth_authorizations\".\"id\" = $3 [[\"last_used_at\", \"2023-01-10 15:20:39.052886\"], [\"updated_at\", \"2023-01-10 15:20:39.053566\"], [\"id\", 1]]"}
web_1 | {"ts":"2023-01-10T15:20:39.057","unix_ts":1673364039.0577188,"severity":"DEBUG","pid":147,"message":" (1.0ms) COMMIT"}
web_1 | {"ts":"2023-01-10T15:20:39.060","unix_ts":1673364039.0603,"severity":"DEBUG","pid":147,"message":" CanvasOauth::Authorization Load (0.7ms) SELECT \"canvas_oauth_authorizations\".* FROM \"canvas_oauth_authorizations\" WHERE \"canvas_oauth_authorizations\".\"canvas_user_id\" = $1 AND \"canvas_oauth_authorizations\".\"tool_consumer_instance_guid\" = $2 ORDER BY created_at DESC LIMIT $3 [[\"canvas_user_id\", 1], [\"tool_consumer_instance_guid\", \"t5yCo*****************kk:canvas-lms\"], [\"LIMIT\", 1]]"}
web_1 | {"ts":"2023-01-10T15:20:39.061","unix_ts":1673364039.061852,"severity":"DEBUG","pid":147,"message":" (0.3ms) BEGIN"}
web_1 | {"ts":"2023-01-10T15:20:39.063","unix_ts":1673364039.0634634,"severity":"DEBUG","pid":147,"message":" CanvasOauth::Authorization Update (1.1ms) UPDATE \"canvas_oauth_authorizations\" SET \"last_used_at\" = $1, \"updated_at\" = $2 WHERE \"canvas_oauth_authorizations\".\"id\" = $3 [[\"last_used_at\", \"2023-01-10 15:20:39.060763\"], [\"updated_at\", \"2023-01-10 15:20:39.061055\"], [\"id\", 1]]"}
web_1 | {"ts":"2023-01-10T15:20:39.064","unix_ts":1673364039.064924,"severity":"DEBUG","pid":147,"message":" (1.0ms) COMMIT"}
web_1 | {"ts":"2023-01-10T15:20:39.160","unix_ts":1673364039.160909,"severity":"INFO","pid":147,"message":"API call (95.77ms): get [\"/api/v1/courses/1/enrollments\", {:query=>{:type=>[\"TeacherEnrollment\", \"TaEnrollment\"], :state=>[\"active\", \"completed\"], :user_id=>\"1\", :per_page=>100}, :headers=>{}}]"}
web_1 | {"ts":"2023-01-10T15:20:39.161","unix_ts":1673364039.1613092,"severity":"ERROR","pid":147,"message":"Exception fetching course: CanvasOauth::CanvasApi::Authenticate"}
web_1 | {"ts":"2023-01-10T15:20:39.162","unix_ts":1673364039.1622174,"severity":"INFO","pid":147,"method":"GET","controller":"SectionsController","action":"course","format":"html","path":"/courses/1","request_id":"c959866d412f1bd4d0859b8ef42f6390","ip":"128.61.61.221","db_runtime":6.104961968958378,"params":{"course_id":"1"}}
web_1 | {"ts":"2023-01-10T15:20:39.163","unix_ts":1673364039.1638973,"severity":"DEBUG","pid":147,"message":"SectionsController#course is missing a template for request formats: text/html excluded from capture: DSN not set"}
web_1 | {"ts":"2023-01-10T15:20:39.164","unix_ts":1673364039.1647792,"severity":"FATAL","pid":147,"message":" \nActionController::MissingExactTemplate (SectionsController#course is missing a template for request formats: text/html):\n \nactionpack (6.0.4.2) lib/action_controller/metal/implicit_render.rb:45:in `default_render'\nactionpack (6.0.4.2) lib/action_controller/metal/basic_implicit_render.rb:6:in `block in send_action'\nactionpack (6.0.4.2) lib/action_controller/metal/basic_implicit_render.rb:6:in `tap'\nactionpack (6.0.4.2) lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'\nactionpack (6.0.4.2) lib/abstract_controller/base.rb:195:in `process_action'\nactionpack (6.0.4.2) lib/action_controller/metal/rendering.rb:30:in `process_action'\nactionpack (6.0.4.2) lib/abstract_controller/callbacks.rb:42:in `block in process_action'\nactivesupport (6.0.4.2) lib/active_support/callbacks.rb:112:in `block in run_callbacks'\nsentry-raven (2.13.0) lib/raven/integrations/rails/controller_transaction.rb:7:in `block in included'\nactivesupport (6.0.4.2) lib/active_support/callbacks.rb:121:in `instance_exec'\nactivesupport (6.0.4.2) lib/active_support/callbacks.rb:121:in `block in run_callbacks'\nactivesupport (6.0.4.2) lib/active_support/callbacks.rb:139:in `run_callbacks'\nactionpack (6.0.4.2) lib/abstract_controller/callbacks.rb:41:in `process_action'\nactionpack (6.0.4.2) lib/action_controller/metal/rescue.rb:22:in `process_action'\npaul_bunyan (1.6.0) lib/paul_bunyan/rails_ext/instrumentation.rb:12:in `block in process_action'\nactivesupport (6.0.4.2) lib/active_support/notifications.rb:180:in `block in instrument'\nactivesupport (6.0.4.2) lib/active_support/notifications/instrumenter.rb:24:in `instrument'\nactivesupport (6.0.4.2) lib/active_support/notifications.rb:180:in `instrument'\npaul_bunyan (1.6.0) lib/paul_bunyan/rails_ext/instrumentation.rb:10:in `process_action'\nactionpack (6.0.4.2) lib/action_controller/metal/params_wrapper.rb:245:in `process_action'\nactiverecord (6.0.4.2) lib/active_record/railties/controller_runtime.rb:27:in `process_action'\nactionpack (6.0.4.2) lib/abstract_controller/base.rb:136:in `process'\nactionview (6.0.4.2) lib/action_view/rendering.rb:39:in `process'\nactionpack (6.0.4.2) lib/action_controller/metal.rb:190:in `dispatch'\nactionpack (6.0.4.2) lib/action_controller/metal.rb:254:in `dispatch'\nactionpack (6.0.4.2) lib/action_dispatch/routing/route_set.rb:50:in `dispatch'\nactionpack (6.0.4.2) lib/action_dispatch/routing/route_set.rb:33:in `serve'\nactionpack (6.0.4.2) lib/action_dispatch/journey/router.rb:49:in `block in serve'\nactionpack (6.0.4.2) lib/action_dispatch/journey/router.rb:32:in `each'\nactionpack (6.0.4.2) lib/action_dispatch/journey/router.rb:32:in `serve'\nactionpack (6.0.4.2) lib/action_dispatch/routing/route_set.rb:834:in `call'\nrack (2.2.3) lib/rack/tempfile_reaper.rb:15:in `call'\nrack (2.2.3) lib/rack/etag.rb:27:in `call'\nrack (2.2.3) lib/rack/conditional_get.rb:27:in `call'\nrack (2.2.3) lib/rack/head.rb:12:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/http/content_security_policy.rb:18:in `call'\nrack (2.2.3) lib/rack/session/abstract/id.rb:266:in `context'\nrack (2.2.3) lib/rack/session/abstract/id.rb:260:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/cookies.rb:654:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'\nactivesupport (6.0.4.2) lib/active_support/callbacks.rb:101:in `run_callbacks'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/callbacks.rb:26:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/actionable_exceptions.rb:18:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/debug_exceptions.rb:32:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'\npaul_bunyan (1.6.0) lib/paul_bunyan/rails_ext/rack_logger.rb:13:in `call_app'\nrailties (6.0.4.2) lib/rails/rack/logger.rb:26:in `block in call'\npaul_bunyan (1.6.0) lib/paul_bunyan/log_relayer.rb:138:in `tagged'\nrailties (6.0.4.2) lib/rails/rack/logger.rb:26:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/remote_ip.rb:81:in `call'\nrequest_store (1.5.0) lib/request_store/middleware.rb:19:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/request_id.rb:27:in `call'\nrack (2.2.3) lib/rack/method_override.rb:24:in `call'\nrack (2.2.3) lib/rack/runtime.rb:22:in `call'\nactivesupport (6.0.4.2) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/executor.rb:14:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/static.rb:126:in `call'\nrack (2.2.3) lib/rack/sendfile.rb:110:in `call'\nactionpack (6.0.4.2) lib/action_dispatch/middleware/host_authorization.rb:76:in `call'\nsentry-raven (2.13.0) lib/raven/integrations/rack.rb:51:in `call'\nrailties (6.0.4.2) lib/rails/engine.rb:527:in `call'\nrailties (6.0.4.2) lib/rails/railtie.rb:190:in `public_send'\nrailties (6.0.4.2) lib/rails/railtie.rb:190:in `method_missing'\n/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/thread_handler_extension.rb:107:in `process_request'\n/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:149:in `accept_and_process_next_request'\n/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:110:in `main_loop'\n/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler.rb:419:in `block (3 levels) in start_threads'\n/usr/lib/ruby/vendor_ruby/phusion_passenger/utils.rb:113:in `block in create_thread_and_abort_on_exception'"}