Hi everyone.
I started this post in October but didn't need to follow up on the issue back then because we had a new installed of our Webwork server . But now we started to have the same issue again.
Some students (not all and students from different courses) started to have issue with the LTI login. A similar message as below shows to them:
"Your authentication failed. Please try again. Please speak with your instructor if you need help.
MA119-S21-BSosnovski-F24 uses an external authentication system. You've authenticated through that system, but aren't allowed to log in to this course"
This happens to students who were able to login before to access a homework assignments and then they were not able to login for some time. Some of students started to have access again days later.
I followed all the steps and checks listed above by Nathan, but nothing resolved the issue. The debug.log file says that "Failed to verify nonce". I have changed the $NonceLifeTime in the file authen_LTI.cong several times as suggested by other post in the forum. Each time set to a larger values (it is now 600) but didn't resolve the issue.
Any idea what else to do or where to look for a possible solution?
Thanks.
------------------------------
Here is a sample of the error as shown in the debug.log:
[Thu Feb 04 14:00:25.214114 2021] WeBWorK::dispatch: The URLPath looks good, we'll add it to the request.[Thu Feb 04 14:00:25.214140 2021] WeBWorK::dispatch: Now we want to look at the parameters we got.
[Thu Feb 04 14:00:25.214162 2021] WeBWorK::dispatch: The raw params:
[Thu Feb 04 14:00:25.214398 2021] WeBWorK::dispatch: lis_person_name_given => 'Dxxxxx’
[Thu Feb 04 14:00:25.214446 2021] WeBWorK::dispatch: tool_consumer_instance_description => 'City University of New York'
[Thu Feb 04 14:00:25.214473 2021] WeBWorK::dispatch: custom_caliper_federated_session_id => 'https://caliper-mapping.cloudbb.blackboard.com/v1/sites/7ecc20d6-ef11-43ac-a3bd-6cd9edce367b/sessions/4FD2074BFC558D49BDB2993E08DFD476'
[Thu Feb 04 14:00:25.214498 2021] WeBWorK::dispatch: lis_result_sourcedid => 'bbgc52825049gi13011907'
[Thu Feb 04 14:00:25.214522 2021] WeBWorK::dispatch: custom_caliper_profile_url => 'https://cunyprod.blackboard.com/learn/api/v1/telemetry/caliper/profile/_54925451_1'
[Thu Feb 04 14:00:25.214546 2021] WeBWorK::dispatch: lis_person_sourcedid => ‘xxxxxxx’
[Thu Feb 04 14:00:25.214570 2021] WeBWorK::dispatch: user_id => 'f114c8e33ea84de486366b5f23e849cc'
[Thu Feb 04 14:00:25.214594 2021] WeBWorK::dispatch: lis_outcome_service_url => 'https://bbhosted.cuny.edu/webapps/gradebook/lti11grade'
[Thu Feb 04 14:00:25.214618 2021] WeBWorK::dispatch: lti_message_type => 'basic-lti-launch-request'
[Thu Feb 04 14:00:25.214643 2021] WeBWorK::dispatch: ext_launch_presentation_css_url => 'https://bbhosted.cuny.edu/common/shared.css,https://bbhosted.cuny.edu/themes/as_2015/theme.css,https://bbhosted.cuny.edu/branding/_1_1/brand.css?ts=1577714075000'
[Thu Feb 04 14:00:25.214667 2021] WeBWorK::dispatch: resource_link_id => '_54925451_1'
[Thu Feb 04 14:00:25.214690 2021] WeBWorK::dispatch: launch_presentation_locale => 'en-US'
[Thu Feb 04 14:00:25.214714 2021] WeBWorK::dispatch: oauth_consumer_key => 'Webwork'
[Thu Feb 04 14:00:25.214738 2021] WeBWorK::dispatch: oauth_version => '1.0'
[Thu Feb 04 14:00:25.214762 2021] WeBWorK::dispatch: context_title => '2021 Spring Term (1) Statistics MA 336 F24[45540] (Queensborough CC)'
[Thu Feb 04 14:00:25.214785 2021] WeBWorK::dispatch: ext_launch_id => 'b1c249ed-1351-4305-9e27-a7abab84d8f8'
[Thu Feb 04 14:00:25.214809 2021] WeBWorK::dispatch: roles => 'urn:lti:role:ims/lis/Learner'
[Thu Feb 04 14:00:25.214833 2021] WeBWorK::dispatch: lis_person_contact_email_primary => ‘xxxx.xxxxxx@student.qcc.cuny.edu'
[Thu Feb 04 14:00:25.214856 2021] WeBWorK::dispatch: oauth_callback => 'about:blank'
[Thu Feb 04 14:00:25.214879 2021] WeBWorK::dispatch: ext_lms => 'bb-3800.0.7-rel.16+bf839b4'
[Thu Feb 04 14:00:25.214903 2021] WeBWorK::dispatch: oauth_signature_method => 'HMAC-SHA1'
[Thu Feb 04 14:00:25.214926 2021] WeBWorK::dispatch: custom_tc_profile_url => 'https://bbhosted.cuny.edu/learn/api/v1/lti/profile?lti_version=LTI-1p0'
[Thu Feb 04 14:00:25.214950 2021] WeBWorK::dispatch: tool_consumer_instance_guid => '153e1b39d0e347519efa1253baed9da8'
[Thu Feb 04 14:00:25.214973 2021] WeBWorK::dispatch: lis_person_name_family => 'Nxxxxx’
[Thu Feb 04 14:00:25.214997 2021] WeBWorK::dispatch: lti_version => 'LTI-1p0'
[Thu Feb 04 14:00:25.215020 2021] WeBWorK::dispatch: lis_person_name_full => 'Dxxxxx Nxxxx Nxxx’
[Thu Feb 04 14:00:25.215043 2021] WeBWorK::dispatch: oauth_nonce => '3414564115058802'
[Thu Feb 04 14:00:25.215072 2021] WeBWorK::dispatch: tool_consumer_instance_name => 'City University of New York'
[Thu Feb 04 14:00:25.215097 2021] WeBWorK::dispatch: launch_presentation_return_url => 'https://bbhosted.cuny.edu/webapps/blackboard/execute/blti/launchReturn?course_id=_1982008_1&content_id=_54925451_1&toGC=false&launch_id=b1c249ed-1351-4305-9e27-a7abab84d8f8&link_id=_54925451_1&launch_time=1612464381020'
[Thu Feb 04 14:00:25.215121 2021] WeBWorK::dispatch: tool_consumer_info_version => '3800.0.7-rel.16+bf839b4'
[Thu Feb 04 14:00:25.215144 2021] WeBWorK::dispatch: context_id => '242fa12435f24913886de98475876420'
[Thu Feb 04 14:00:25.215168 2021] WeBWorK::dispatch: oauth_signature => 'oUJROHckKcZsNbmU/YLbS2fkVWk='
[Thu Feb 04 14:00:25.215192 2021] WeBWorK::dispatch: context_label => 'QCC01_MA_119_F24_1212_1'
[Thu Feb 04 14:00:25.215215 2021] WeBWorK::dispatch: tool_consumer_info_product_family_code => 'BlackboardLearn'
[Thu Feb 04 14:00:25.215239 2021] WeBWorK::dispatch: oauth_timestamp => '1612464381'
[Thu Feb 04 14:00:25.215262 2021] WeBWorK::dispatch: launch_presentation_document_target => 'window'
[Thu Feb 04 14:00:25.215286 2021] WeBWorK::dispatch: resource_link_title => 'HW Intro to WeBWorK'
[Thu Feb 04 14:00:25.215309 2021] WeBWorK::dispatch: tool_consumer_instance_contact_email => 'bbsupport@cuny.edu'
[Thu Feb 04 14:00:25.215333 2021] WeBWorK::dispatch: ---------------------------------------------
[Thu Feb 04 14:00:25.215367 2021] WeBWorK::dispatch: We need to get a course environment (with or without a courseID!)
[Thu Feb 04 14:00:25.220954 2021] WeBWorK::dispatch: Here's the course environment: WeBWorK::CourseEnvironment=HASH(0x55bb6bce3898)
[Thu Feb 04 14:00:25.221330 2021] WeBWorK::dispatch: Using user_authen_module WeBWorK::Authen::LTIAdvanced: WeBWorK::Authen::LTIAdvanced=HASH(0x55bb6bdc20b8)
[Thu Feb 04 14:00:25.221388 2021] WeBWorK::dispatch: We got a courseID from the URLPath, now we can do some stuff:
[Thu Feb 04 14:00:25.221420 2021] WeBWorK::dispatch: ...we can create a database object...
[Thu Feb 04 14:00:25.227112 2021] WeBWorK::dispatch: (here's the DB handle: WeBWorK::DB=HASH(0x55bb6bd398e8))
[Thu Feb 04 14:00:25.227194 2021] WeBWorK::Authen::verify: BEGIN VERIFY
[Thu Feb 04 14:00:25.227230 2021] WeBWorK::Authen::LTIAdvanced::request_has_data_for_this_verification_module: LTIAdvanced has been called for data verification
[Thu Feb 04 14:00:25.227271 2021] WeBWorK::Authen::LTIAdvanced::request_has_data_for_this_verification_module: LTIAdvanced returning that it has sufficient data
[Thu Feb 04 14:00:25.227316 2021] WeBWorK::Authen::do_verify: db ok
[Thu Feb 04 14:00:25.227344 2021] WeBWorK::Authen::LTIAdvanced::get_credentials: LTIAdvanced::get_credentials has been called
[Thu Feb 04 14:00:25.227415 2021] WeBWorK::Authen::LTIAdvanced::get_credentials: LTIAdvanced::get_credentials is returning a 1
[Thu Feb 04 14:00:25.227442 2021] WeBWorK::Authen::do_verify: credentials ok
[Thu Feb 04 14:00:25.227473 2021] WeBWorK::Authen::LTIAdvanced::check_user: LTIAdvanced::check_user has been called for user_id = |xxxxxxxxxx|
[Thu Feb 04 14:00:25.228633 2021] WeBWorK::Authen::LTIAdvanced::check_user: LTIAdvanced::check_user is about to return a 1.
[Thu Feb 04 14:00:25.228706 2021] WeBWorK::Authen::do_verify: check user ok
[Thu Feb 04 14:00:25.228751 2021] WeBWorK::Authen::LTIAdvanced::verify_normal_user: LTIAdvanced::verify_normal_user called for user |xxxxxxxx|
[Thu Feb 04 14:00:25.229102 2021] WeBWorK::Authen::LTIAdvanced::verify_normal_user: sessionExists='1' keyMatches='' timestampValid=''
[Thu Feb 04 14:00:25.229166 2021] WeBWorK::Authen::LTIAdvanced::authenticate: LTIAdvanced::authenticate called for user |xxxxxxxxxx|
[Thu Feb 04 14:00:25.229195 2021] WeBWorK::Authen::LTIAdvanced::authenticate: ref(r) = |WeBWorK::Request|
[Thu Feb 04 14:00:25.229220 2021] WeBWorK::Authen::LTIAdvanced::authenticate: ref of r->{paramcache} = |HASH|
[Thu Feb 04 14:00:25.229248 2021] WeBWorK::Authen::LTIAdvanced::authenticate: Nonce = |3414564115058802|
[Thu Feb 04 14:00:25.229567 2021] WeBWorK::Authen::LTIAdvanced::authenticate: Failed to verify nonce
[Thu Feb 04 14:00:25.229628 2021] WeBWorK::Authen::LTIAdvanced::verify_normal_user: auth_result=|0|
[Thu Feb 04 14:00:25.229763 2021] WeBWorK::Authen::write_log_entry: Writing to login log: 'LOGIN FAILED xxxxxxxx - authentication failed: 0 user_id=xxxxxxx login_type=normal credential_source=LTIAdvanced host=100.2.88.126 port=63526 UA=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/88.0.4324.96 Safari/537.36 Edg/88.0.705.56'.
[Thu Feb 04 14:00:25.230070 2021] WeBWorK::Authen::verify: END VERIFY
[Thu Feb 04 14:00:25.230105 2021] WeBWorK::Authen::verify: result 0
[Thu Feb 04 14:00:25.230142 2021] WeBWorK::dispatch: Bad news: authentication failed!
[Thu Feb 04 14:00:25.230168 2021] WeBWorK::dispatch: set displayModule to WeBWorK::ContentGenerator::Login
[Thu Feb 04 14:00:25.230192 2021] WeBWorK::dispatch: --------------------------------------------------------------------------------
[Thu Feb 04 14:00:25.230214 2021] WeBWorK::dispatch: Finally, we'll load the display module...
[Thu Feb 04 14:00:25.230289 2021] WeBWorK::dispatch: ...instantiate it...
[Thu Feb 04 14:00:25.230336 2021] WeBWorK::dispatch: ...and call it:
[Thu Feb 04 14:00:25.230361 2021] WeBWorK::dispatch: -------------------- call to WeBWorK::ContentGenerator::Login::go
[Thu Feb 04 14:00:25.233233 2021] WeBWorK::dispatch: -------------------- call to WeBWorK::ContentGenerator::Login::go
[Thu Feb 04 14:00:25.233422 2021] WeBWorK::dispatch: returning result: 0
[Thu Feb 04 14:00:25.393891 2021] WeBWorK::dispatch: