webwork2.log --> [2024-10-22 09:10:38.37717] [5174] [info] WeBWorK server is starting [2024-10-22 09:10:38.37729] [5174] [info] WeBWorK root directory set to /opt/webwork/webwork2 [2024-10-22 09:10:38.37731] [5174] [info] PG root directory set to /opt/webwork/pg [2024-10-22 09:10:38.37733] [5174] [info] The webwork url on this site is https://xxx.de/webwork2 [2024-10-22 09:10:38.37735] [5174] [info] webwork_server_admin_email for reporting bugs has been set to thore.saathoff@xxx.de in site.conf [2024-10-22 09:10:38.39530] [5174] [info] SOAP endpoints enabled [2024-10-22 09:10:38.40194] [5174] [info] Listening at "https://*:8080?cert=%2Fetc%2Fssl%2Fxxx%2Fww.crt&key=%2Fetc%2Fssl%2Fxxx%2Fww.key" [2024-10-22 09:10:38.40572] [5176] [info] Manager 5176 started ... [2024-10-22 09:10:38.44502] [5200] [info] Worker 5200 started [2024-10-22 09:10:38.44642] [5176] [info] Creating process id file "/run/webwork2/webwork2.pid" [2024-10-22 09:10:38.44657] [5201] [info] Worker 5201 started [2024-10-22 09:10:51.82709] [5177] [warn] [1H4oya65iLDU] [/webwork2/test1/instructor/setmaker] Use of uninitialized value in hash element at template ContentGenerator/Login.html.ep line 12. [2024-10-22 09:10:51.82713] [5177] [warn] [1H4oya65iLDU] [/webwork2/test1/instructor/setmaker] Use of uninitialized value in hash element at template ContentGenerator/Login.html.ep line 12. debug.log --> [Tue Oct 22 09:10:41.866474 2024] (eval): ===> Begin WeBWorK::dispatch() <=== [Tue Oct 22 09:10:41.866711 2024] (eval): Hi, I'm the new dispatcher! [Tue Oct 22 09:10:41.866763 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:41.866795 2024] (eval): Okay, I got some basic information: [Tue Oct 22 09:10:41.866858 2024] (eval): The site location is /webwork2 [Tue Oct 22 09:10:41.866900 2024] (eval): The request method is GET [Tue Oct 22 09:10:41.867085 2024] (eval): The URI is /webwork2/test1 [Tue Oct 22 09:10:41.867139 2024] (eval): The argument string is [Tue Oct 22 09:10:41.867176 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:41.867233 2024] (eval): The path is /test1/ [Tue Oct 22 09:10:41.867288 2024] (eval): The current route is set_list [Tue Oct 22 09:10:41.867317 2024] (eval): Here is some information about this route: [Tue Oct 22 09:10:41.867348 2024] (eval): The display module for this route is WeBWorK::ContentGenerator::ProblemSets [Tue Oct 22 09:10:41.867382 2024] (eval): This route has the following captures: [Tue Oct 22 09:10:41.867414 2024] (eval): courseID => test1 [Tue Oct 22 09:10:41.867447 2024] (eval): action => go [Tue Oct 22 09:10:41.867474 2024] (eval): controller => ProblemSets [Tue Oct 22 09:10:41.867510 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:41.867543 2024] (eval): Now we want to look at the parameters we got. [Tue Oct 22 09:10:41.867572 2024] (eval): The raw params: [Tue Oct 22 09:10:41.867618 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:41.867651 2024] (eval): We need to get a course environment (with or without a courseID!) [Tue Oct 22 09:10:41.871269 2024] (eval): Here's the course environment: WeBWorK::CourseEnvironment=HASH(0x5fa425e5f990) [Tue Oct 22 09:10:41.890147 2024] (eval): Using authentication module WeBWorK::Authen::Shibboleth: WeBWorK::Authen::Shibboleth=HASH(0x5fa41fcd3938) [Tue Oct 22 09:10:41.890207 2024] (eval): We got a courseID from the route, now we can do some stuff: [Tue Oct 22 09:10:41.890255 2024] (eval): ...we can create a database object... [Tue Oct 22 09:10:41.911655 2024] (eval): (here's the DB handle: WeBWorK::DB=HASH(0x5fa41fcd3ab8)) [Tue Oct 22 09:10:41.911726 2024] WeBWorK::Authen::verify: BEGIN VERIFY [Tue Oct 22 09:10:41.911761 2024] WeBWorK::Authen::do_verify: db ok [Tue Oct 22 09:10:41.911794 2024] WeBWorK::Authen::Shibboleth::get_credentials: Shib is on! [Tue Oct 22 09:10:41.911832 2024] WeBWorK::Authen::Shibboleth::get_credentials: Got shib header (mail) and user_id (thore.syyyyyf@xxx.de) [Tue Oct 22 09:10:41.911907 2024] WeBWorK::Authen::do_verify: credentials ok [Tue Oct 22 09:10:41.912640 2024] WeBWorK::Authen::do_verify: check user ok [Tue Oct 22 09:10:41.913002 2024] WeBWorK::Authen::verify_normal_user: sessionExists='0' keyMatches='###UNDEF###' timestampValid='###UNDEF###' [Tue Oct 22 09:10:41.914294 2024] WeBWorK::Authen::write_log_entry: Writing to login log: 'AUTH WWDB: password rejected, deferring to site_checkPassword user_id=thore.syyyyyf@xxx.de login_type=normal credential_source=params host=ip.ip.ip.ip port=46716 UA=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0'. [Tue Oct 22 09:10:41.915767 2024] WeBWorK::Authen::write_log_entry: Writing to login log: 'LOGIN OK user_id=thore.syyyyyf@xxx.de login_type=normal credential_source=params host=ip.ip.ip.ip port=46716 UA=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0'. [Tue Oct 22 09:10:41.915931 2024] WeBWorK::Authen::set_params: params user='thore.syyyyyf@xxx.de' key='xxxxxxxxxxxxxxkeyxxxxxxxxxxxxxxxxxx' [Tue Oct 22 09:10:41.915987 2024] WeBWorK::Authen::verify: END VERIFY [Tue Oct 22 09:10:41.916015 2024] WeBWorK::Authen::verify: result 1 [Tue Oct 22 09:10:41.916053 2024] (eval): Hi, thore.syyyyyf@xxx.de, glad you made it. [Tue Oct 22 09:10:41.916564 2024] (eval): Now we deal with the effective user: [Tue Oct 22 09:10:41.916622 2024] (eval): userID=thore.syyyyyf@xxx.de eUserID=thore.syyyyyf@xxx.de [Tue Oct 22 09:10:41.916987 2024] WeBWorK::ContentGenerator::ProblemSets::initialize: Begin collecting merged sets [Tue Oct 22 09:10:41.917475 2024] WeBWorK::ContentGenerator::ProblemSets::initialize: Begin sorting merged sets [Tue Oct 22 09:10:41.917529 2024] WeBWorK::ContentGenerator::ProblemSets::initialize: End preparing merged sets [Tue Oct 22 09:10:41.940442 2024] WeBWorK::Authen::store_session: Saving database session. The database session contains { "key" => "xxxxxxxxxxxxxxkeyxxxxxxxxxxxxxxxxxx", "session" => {}, "timestamp" => 1729581041, "user_id" => "thore.syyyyyf\@tuhh.de" } [Tue Oct 22 09:10:43.719422 2024] (eval): ===> Begin WeBWorK::dispatch() <=== [Tue Oct 22 09:10:43.719608 2024] (eval): Hi, I'm the new dispatcher! [Tue Oct 22 09:10:43.719642 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:43.719668 2024] (eval): Okay, I got some basic information: [Tue Oct 22 09:10:43.719690 2024] (eval): The site location is /webwork2 [Tue Oct 22 09:10:43.719712 2024] (eval): The request method is GET [Tue Oct 22 09:10:43.719817 2024] (eval): The URI is /webwork2/test1/instructor/pgProblemEditor [Tue Oct 22 09:10:43.719877 2024] (eval): The argument string is effectiveUser=thore.syyyyyf%40tuhh.de [Tue Oct 22 09:10:43.719904 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:43.719951 2024] (eval): The path is /test1/instructor/pgProblemEditor/ [Tue Oct 22 09:10:43.719992 2024] (eval): The current route is instructor_problem_editor [Tue Oct 22 09:10:43.720015 2024] (eval): Here is some information about this route: [Tue Oct 22 09:10:43.720041 2024] (eval): The display module for this route is WeBWorK::ContentGenerator::Instructor::PGProblemEditor [Tue Oct 22 09:10:43.720062 2024] (eval): This route has the following captures: [Tue Oct 22 09:10:43.720086 2024] (eval): courseID => test1 [Tue Oct 22 09:10:43.720113 2024] (eval): controller => Instructor::PGProblemEditor [Tue Oct 22 09:10:43.720135 2024] (eval): action => go [Tue Oct 22 09:10:43.720156 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:43.720177 2024] (eval): Now we want to look at the parameters we got. [Tue Oct 22 09:10:43.720200 2024] (eval): The raw params: [Tue Oct 22 09:10:43.720257 2024] (eval): effectiveUser => "thore.syyyyyf@xxx.de" [Tue Oct 22 09:10:43.720285 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:43.720314 2024] (eval): We need to get a course environment (with or without a courseID!) [Tue Oct 22 09:10:43.723172 2024] (eval): Here's the course environment: WeBWorK::CourseEnvironment=HASH(0x5fa42612a638) [Tue Oct 22 09:10:43.741821 2024] (eval): Using authentication module WeBWorK::Authen::Shibboleth: WeBWorK::Authen::Shibboleth=HASH(0x5fa41fcd37e8) [Tue Oct 22 09:10:43.741899 2024] (eval): We got a courseID from the route, now we can do some stuff: [Tue Oct 22 09:10:43.741935 2024] (eval): ...we can create a database object... [Tue Oct 22 09:10:43.760612 2024] (eval): (here's the DB handle: WeBWorK::DB=HASH(0x5fa41fcd3c38)) [Tue Oct 22 09:10:43.760680 2024] WeBWorK::Authen::verify: BEGIN VERIFY [Tue Oct 22 09:10:43.760714 2024] WeBWorK::Authen::do_verify: db ok [Tue Oct 22 09:10:43.760746 2024] WeBWorK::Authen::Shibboleth::get_credentials: Shib is on! [Tue Oct 22 09:10:43.760776 2024] WeBWorK::Authen::Shibboleth::get_credentials: Got shib header (mail) and user_id (thore.syyyyyf@xxx.de) [Tue Oct 22 09:10:43.760836 2024] WeBWorK::Authen::do_verify: credentials ok [Tue Oct 22 09:10:43.761667 2024] WeBWorK::Authen::do_verify: check user ok [Tue Oct 22 09:10:43.762096 2024] WeBWorK::Authen::verify_normal_user: sessionExists='1' keyMatches='' timestampValid='1' [Tue Oct 22 09:10:43.763411 2024] WeBWorK::Authen::write_log_entry: Writing to login log: 'AUTH WWDB: password rejected, deferring to site_checkPassword user_id=thore.syyyyyf@xxx.de login_type=normal credential_source=params host=ip.ip.ip.ip port=46732 UA=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0'. [Tue Oct 22 09:10:43.764876 2024] WeBWorK::Authen::write_log_entry: Writing to login log: 'LOGIN OK user_id=thore.syyyyyf@xxx.de login_type=normal credential_source=params host=ip.ip.ip.ip port=46732 UA=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0'. [Tue Oct 22 09:10:43.765014 2024] WeBWorK::Authen::set_params: params user='thore.syyyyyf@xxx.de' key='0pmmsYh5yYLHXHy7vqTasMDgOzWr8pgJ' [Tue Oct 22 09:10:43.765068 2024] WeBWorK::Authen::verify: END VERIFY [Tue Oct 22 09:10:43.765097 2024] WeBWorK::Authen::verify: result 1 [Tue Oct 22 09:10:43.765134 2024] (eval): Hi, thore.syyyyyf@xxx.de, glad you made it. [Tue Oct 22 09:10:43.765649 2024] (eval): Now we deal with the effective user: [Tue Oct 22 09:10:43.765701 2024] (eval): userID=thore.syyyyyf@xxx.de eUserID=thore.syyyyyf@xxx.de [Tue Oct 22 09:10:43.809910 2024] WeBWorK::Authen::store_session: Saving database session. The database session contains { "key" => "0pmmsYh5yYLHXHy7vqTasMDgOzWr8pgJ", "session" => {}, "timestamp" => 1729581043, "user_id" => "thore.syyyyyf\@tuhh.de" } [Tue Oct 22 09:10:44.390718 2024] (eval): ===> Begin WeBWorK::dispatch() <=== [Tue Oct 22 09:10:44.390913 2024] (eval): Hi, I'm the new dispatcher! [Tue Oct 22 09:10:44.390952 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:44.390977 2024] (eval): Okay, I got some basic information: [Tue Oct 22 09:10:44.391000 2024] (eval): The site location is /webwork2 [Tue Oct 22 09:10:44.391021 2024] (eval): The request method is POST [Tue Oct 22 09:10:44.391130 2024] (eval): The URI is /webwork2/render_rpc [Tue Oct 22 09:10:44.391169 2024] (eval): The argument string is user=thore.syyyyyf%40tuhh.de&courseID=test1&problemSeed=123456&sourceFilePath=undefined&rawProblemSource=%23%23+DESCRIPTION%0A%23%23+Give+a+value+for+pi%0A%23%23+ENDDESCRIPTION%0A%0A%23%23+KEYWORDS%28%27template%27%2C+%27pi%27%29%0A%0A%23%23+DBsubject%28%27Algebra%27%29%0A%23%23+DBchapter%28%27Fundamentals%27%29%0A%23%23+DBsection%28%27Real+Numbers%27%29%0A%23%23+Date%28%27%27%29%0A%23%23+Author%28%27%27%29%0A%23%23+Institution%28%27%27%29%0A%0ADOCUMENT%28%29%3B%0A%0AloadMacros%28%0A++++%22PGstandard.pl%22%2C++++%23+Standard+macros+for+PG+language%0A++++%22PGML.pl%22%2C++++++++++%23+PGML+markup+and+Math+Objects%0A++++%22PGcourse.pl%22%2C++++++%23+Customization+file+for+the+course%0A%29%3B%0A%0A%23+Uncomment+the+following+if+you+don%27t+want+to+show+which%0A%23+answers+are+correct+and+which+are+incorrect%0A%23%24showPartialCorrectAnswers+%3D+0%3B%0A%0A%24pi+%3D+Real%28%22pi%22%29%3B%0A%0ABEGIN_PGML%0AEnter+a+value+for+%5B%60%5Cpi%60%5D.%0A%0A%5B_%5D%7B%24pi%7D%7B5%7D%0AEND_PGML%0A%0ABEGIN_PGML_SOLUTION%0AYou+could+type+%5B%7Cpi%7C%5D*+or+%5B%7C3.14%7C%5D*%2C+or+%5B%7C22%2F7%7C%5D*%2C%0Aamong+other+options.%0AEND_PGML_SOLUTION%0A%0AENDDOCUMENT%28%29%3B%0A&outputformat=simple&showAnswerNumbers=0&set_id=&probNum=0&showHints=1&showSolutions=1&isInstructor=1&noprepostambles=1&processAnswers=0&showPreviewButton=1&showCheckAnswersButton=1&showCorrectAnswersButton=0&showCorrectAnswersOnlyButton=1&showFooter=0&displayMode=MathJax&language=en&send_pg_flags=1&view_problem_debugging_info=1 [Tue Oct 22 09:10:44.391198 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:44.391240 2024] (eval): The path is /render_rpc/ [Tue Oct 22 09:10:44.391281 2024] (eval): The current route is render_rpc [Tue Oct 22 09:10:44.391304 2024] (eval): Here is some information about this route: [Tue Oct 22 09:10:44.391330 2024] (eval): The display module for this route is WeBWorK::ContentGenerator::RenderViaRPC [Tue Oct 22 09:10:44.391351 2024] (eval): This route has the following captures: [Tue Oct 22 09:10:44.391374 2024] (eval): controller => RenderViaRPC [Tue Oct 22 09:10:44.391408 2024] (eval): action => go [Tue Oct 22 09:10:44.391430 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:44.391451 2024] (eval): Now we want to look at the parameters we got. [Tue Oct 22 09:10:44.391473 2024] (eval): The raw params: [Tue Oct 22 09:10:44.391603 2024] (eval): showCheckAnswersButton => "1" [Tue Oct 22 09:10:44.391634 2024] (eval): sourceFilePath => "undefined" [Tue Oct 22 09:10:44.391658 2024] (eval): courseID => "test1" [Tue Oct 22 09:10:44.391681 2024] (eval): noprepostambles => "1" [Tue Oct 22 09:10:44.391702 2024] (eval): outputformat => "simple" [Tue Oct 22 09:10:44.391723 2024] (eval): send_pg_flags => "1" [Tue Oct 22 09:10:44.391745 2024] (eval): showCorrectAnswersOnlyButton => "1" [Tue Oct 22 09:10:44.391766 2024] (eval): showAnswerNumbers => "0" [Tue Oct 22 09:10:44.391787 2024] (eval): isInstructor => "1" [Tue Oct 22 09:10:44.391808 2024] (eval): view_problem_debugging_info => "1" [Tue Oct 22 09:10:44.391849 2024] (eval): showCorrectAnswersButton => "0" [Tue Oct 22 09:10:44.391876 2024] (eval): showPreviewButton => "1" [Tue Oct 22 09:10:44.391898 2024] (eval): set_id => "" [Tue Oct 22 09:10:44.391920 2024] (eval): showHints => "1" [Tue Oct 22 09:10:44.391941 2024] (eval): user => "thore.syyyyyf@xxx.de" [Tue Oct 22 09:10:44.391962 2024] (eval): probNum => "0" [Tue Oct 22 09:10:44.391983 2024] (eval): displayMode => "MathJax" [Tue Oct 22 09:10:44.392004 2024] (eval): processAnswers => "0" [Tue Oct 22 09:10:44.392024 2024] (eval): showSolutions => "1" [Tue Oct 22 09:10:44.392046 2024] (eval): rawProblemSource => "## DESCRIPTION ## Give a value for pi ## ENDDESCRIPTION ## KEYWORDS('template', 'pi') ## DBsubject('Algebra') ## DBchapter('Fundamentals') ## DBsection('Real Numbers') ## Date('') ## Author('') ## Institution('') DOCUMENT(); loadMacros( "PGstandard.pl", # Standard macros for PG language "PGML.pl", # PGML markup and Math Objects "PGcourse.pl", # Customization file for the course ); # Uncomment the following if you don't want to show which # answers are correct and which are incorrect #$showPartialCorrectAnswers = 0; $pi = Real("pi"); BEGIN_PGML Enter a value for [`\pi`]. [_]{$pi}{5} END_PGML BEGIN_PGML_SOLUTION You could type [|pi|]* or [|3.14|]*, or [|22/7|]*, among other options. END_PGML_SOLUTION ENDDOCUMENT(); " [Tue Oct 22 09:10:44.392071 2024] (eval): language => "en" [Tue Oct 22 09:10:44.392093 2024] (eval): showFooter => "0" [Tue Oct 22 09:10:44.392113 2024] (eval): problemSeed => "123456" [Tue Oct 22 09:10:44.392134 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:44.392168 2024] (eval): We need to get a course environment (with or without a courseID!) [Tue Oct 22 09:10:44.394935 2024] (eval): Here's the course environment: WeBWorK::CourseEnvironment=HASH(0x5fa427826b60) [Tue Oct 22 09:10:44.413538 2024] (eval): Using authentication module WeBWorK::Authen::Shibboleth: WeBWorK::Authen::Shibboleth=HASH(0x5fa41fcd3938) [Tue Oct 22 09:10:44.413597 2024] (eval): We got a courseID from the route, now we can do some stuff: [Tue Oct 22 09:10:44.413631 2024] (eval): ...we can create a database object... [Tue Oct 22 09:10:44.431087 2024] (eval): (here's the DB handle: WeBWorK::DB=HASH(0x5fa41fcd3ba8)) [Tue Oct 22 09:10:44.431157 2024] WeBWorK::Authen::verify: BEGIN VERIFY [Tue Oct 22 09:10:44.431193 2024] WeBWorK::Authen::do_verify: db ok [Tue Oct 22 09:10:44.431226 2024] WeBWorK::Authen::Shibboleth::get_credentials: Shib is on! [Tue Oct 22 09:10:44.431256 2024] WeBWorK::Authen::get_credentials: self is WeBWorK::Authen::Shibboleth=HASH(0x5fa41fcd3938) [Tue Oct 22 09:10:44.431307 2024] WeBWorK::Authen::get_credentials: credential source: "params", user: "thore.syyyyyf@xxx.de" key: "###UNDEF###" [Tue Oct 22 09:10:44.431335 2024] WeBWorK::Authen::do_verify: credentials ok [Tue Oct 22 09:10:44.432164 2024] WeBWorK::Authen::do_verify: check user ok [Tue Oct 22 09:10:44.432591 2024] WeBWorK::Authen::verify_normal_user: sessionExists='1' keyMatches='' timestampValid='1' [Tue Oct 22 09:10:44.432794 2024] WeBWorK::Authen::verify: END VERIFY [Tue Oct 22 09:10:44.432852 2024] WeBWorK::Authen::verify: result 0 [Tue Oct 22 09:10:44.433396 2024] WeBWorK::Authen::store_session: Deleting database session. [Tue Oct 22 09:10:46.894771 2024] (eval): ===> Begin WeBWorK::dispatch() <=== [Tue Oct 22 09:10:46.894942 2024] (eval): Hi, I'm the new dispatcher! [Tue Oct 22 09:10:46.894999 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:46.895042 2024] (eval): Okay, I got some basic information: [Tue Oct 22 09:10:46.895082 2024] (eval): The site location is /webwork2 [Tue Oct 22 09:10:46.895120 2024] (eval): The request method is GET [Tue Oct 22 09:10:46.895206 2024] (eval): The URI is /webwork2/test1/instructor/setmaker [Tue Oct 22 09:10:46.895249 2024] (eval): The argument string is effectiveUser=thore.syyyyyf%40tuhh.de [Tue Oct 22 09:10:46.895288 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:46.895352 2024] (eval): The path is /test1/instructor/setmaker/ [Tue Oct 22 09:10:46.895412 2024] (eval): The current route is instructor_set_maker [Tue Oct 22 09:10:46.895453 2024] (eval): Here is some information about this route: [Tue Oct 22 09:10:46.895495 2024] (eval): The display module for this route is WeBWorK::ContentGenerator::Instructor::SetMaker [Tue Oct 22 09:10:46.895532 2024] (eval): This route has the following captures: [Tue Oct 22 09:10:46.895570 2024] (eval): action => go [Tue Oct 22 09:10:46.895607 2024] (eval): controller => Instructor::SetMaker [Tue Oct 22 09:10:46.895643 2024] (eval): courseID => test1 [Tue Oct 22 09:10:46.895680 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:46.895717 2024] (eval): Now we want to look at the parameters we got. [Tue Oct 22 09:10:46.895754 2024] (eval): The raw params: [Tue Oct 22 09:10:46.895856 2024] (eval): effectiveUser => "thore.syyyyyf@xxx.de" [Tue Oct 22 09:10:46.895911 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:46.895957 2024] (eval): We need to get a course environment (with or without a courseID!) [Tue Oct 22 09:10:46.899886 2024] (eval): Here's the course environment: WeBWorK::CourseEnvironment=HASH(0x5fa427b89280) [Tue Oct 22 09:10:46.900131 2024] (eval): Using authentication module WeBWorK::Authen::Shibboleth: WeBWorK::Authen::Shibboleth=HASH(0x5fa427b0fd18) [Tue Oct 22 09:10:46.900177 2024] (eval): We got a courseID from the route, now we can do some stuff: [Tue Oct 22 09:10:46.900209 2024] (eval): ...we can create a database object... [Tue Oct 22 09:10:46.905909 2024] (eval): (here's the DB handle: WeBWorK::DB=HASH(0x5fa427af7848)) [Tue Oct 22 09:10:46.905966 2024] WeBWorK::Authen::verify: BEGIN VERIFY [Tue Oct 22 09:10:46.906000 2024] WeBWorK::Authen::do_verify: db ok [Tue Oct 22 09:10:46.906035 2024] WeBWorK::Authen::Shibboleth::get_credentials: Shib is on! [Tue Oct 22 09:10:46.906066 2024] WeBWorK::Authen::Shibboleth::get_credentials: Got shib header (mail) and user_id (thore.syyyyyf@xxx.de) [Tue Oct 22 09:10:46.906113 2024] WeBWorK::Authen::do_verify: credentials ok [Tue Oct 22 09:10:46.906788 2024] WeBWorK::Authen::do_verify: check user ok [Tue Oct 22 09:10:46.907112 2024] WeBWorK::Authen::verify_normal_user: sessionExists='0' keyMatches='###UNDEF###' timestampValid='###UNDEF###' [Tue Oct 22 09:10:46.908386 2024] WeBWorK::Authen::write_log_entry: Writing to login log: 'AUTH WWDB: password rejected, deferring to site_checkPassword user_id=thore.syyyyyf@xxx.de login_type=normal credential_source=params host=ip.ip.ip.ip port=46748 UA=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0'. [Tue Oct 22 09:10:46.909577 2024] WeBWorK::Authen::write_log_entry: Writing to login log: 'LOGIN OK user_id=thore.syyyyyf@xxx.de login_type=normal credential_source=params host=ip.ip.ip.ip port=46748 UA=Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0'. [Tue Oct 22 09:10:46.909723 2024] WeBWorK::Authen::set_params: params user='thore.syyyyyf@xxx.de' key='yyyyyyyyyyyyyyyykeyyyyyyyyyyyyyyyyyyyyyyyyy' [Tue Oct 22 09:10:46.909763 2024] WeBWorK::Authen::verify: END VERIFY [Tue Oct 22 09:10:46.909788 2024] WeBWorK::Authen::verify: result 1 [Tue Oct 22 09:10:46.909819 2024] (eval): Hi, thore.syyyyyf@xxx.de, glad you made it. [Tue Oct 22 09:10:46.910348 2024] (eval): Now we deal with the effective user: [Tue Oct 22 09:10:46.910394 2024] (eval): userID=thore.syyyyyf@xxx.de eUserID=thore.syyyyyf@xxx.de [Tue Oct 22 09:10:46.910960 2024] WeBWorK::ContentGenerator::Instructor::SetMaker::pre_header_initialize: browse_libeffectiveUserpasswdkeyuser [Tue Oct 22 09:10:46.911006 2024] WeBWorK::ContentGenerator::Instructor::SetMaker::pre_header_initialize: browse_opl [Tue Oct 22 09:10:46.911032 2024] WeBWorK::ContentGenerator::Instructor::SetMaker::pre_header_initialize: browse_course_sets [Tue Oct 22 09:10:46.911054 2024] WeBWorK::ContentGenerator::Instructor::SetMaker::pre_header_initialize: browse_setdefs [Tue Oct 22 09:10:47.191907 2024] WeBWorK::Authen::store_session: Saving database session. The database session contains { "key" => "yyyyyyyyyyyyyyyykeyyyyyyyyyyyyyyyyyyyyyyyyy", "session" => {}, "timestamp" => 1729581046, "user_id" => "thore.syyyyyf\@tuhh.de" } [Tue Oct 22 09:10:49.741911 2024] (eval): ===> Begin WeBWorK::dispatch() <=== [Tue Oct 22 09:10:49.742071 2024] (eval): Hi, I'm the new dispatcher! [Tue Oct 22 09:10:49.742104 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:49.742129 2024] (eval): Okay, I got some basic information: [Tue Oct 22 09:10:49.742151 2024] (eval): The site location is /webwork2 [Tue Oct 22 09:10:49.742172 2024] (eval): The request method is POST [Tue Oct 22 09:10:49.742278 2024] (eval): The URI is /webwork2/instructor_rpc [Tue Oct 22 09:10:49.742316 2024] (eval): The argument string is rpc_command=searchLib&library_name=Library&command=getAllDBchapters&user=thore.syyyyyf%40tuhh.de&courseID=test1&library_subject=1&library_chapter=&library_section=&library_textbook=&library_textchapter=&library_textsection=&includeOPL=1&includeContrib=0&level=&library_keywords= [Tue Oct 22 09:10:49.742343 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:49.742386 2024] (eval): The path is /instructor_rpc/ [Tue Oct 22 09:10:49.742425 2024] (eval): The current route is instructor_rpc [Tue Oct 22 09:10:49.742448 2024] (eval): Here is some information about this route: [Tue Oct 22 09:10:49.742475 2024] (eval): The display module for this route is WeBWorK::ContentGenerator::InstructorRPCHandler [Tue Oct 22 09:10:49.742496 2024] (eval): This route has the following captures: [Tue Oct 22 09:10:49.742520 2024] (eval): action => go [Tue Oct 22 09:10:49.742540 2024] (eval): controller => InstructorRPCHandler [Tue Oct 22 09:10:49.742561 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:49.742581 2024] (eval): Now we want to look at the parameters we got. [Tue Oct 22 09:10:49.742602 2024] (eval): The raw params: [Tue Oct 22 09:10:49.742698 2024] (eval): library_textchapter => "" [Tue Oct 22 09:10:49.742728 2024] (eval): level => "" [Tue Oct 22 09:10:49.742752 2024] (eval): library_keywords => "" [Tue Oct 22 09:10:49.742774 2024] (eval): command => "getAllDBchapters" [Tue Oct 22 09:10:49.742796 2024] (eval): includeContrib => "0" [Tue Oct 22 09:10:49.742818 2024] (eval): library_chapter => "" [Tue Oct 22 09:10:49.742859 2024] (eval): library_section => "" [Tue Oct 22 09:10:49.742886 2024] (eval): user => "thore.syyyyyf@xxx.de" [Tue Oct 22 09:10:49.742912 2024] (eval): library_textsection => "" [Tue Oct 22 09:10:49.742933 2024] (eval): library_textbook => "" [Tue Oct 22 09:10:49.742953 2024] (eval): courseID => "test1" [Tue Oct 22 09:10:49.742974 2024] (eval): library_subject => "1" [Tue Oct 22 09:10:49.743006 2024] (eval): library_name => "Library" [Tue Oct 22 09:10:49.743028 2024] (eval): rpc_command => "searchLib" [Tue Oct 22 09:10:49.743048 2024] (eval): includeOPL => "1" [Tue Oct 22 09:10:49.743068 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:49.743098 2024] (eval): We need to get a course environment (with or without a courseID!) [Tue Oct 22 09:10:49.745896 2024] (eval): Here's the course environment: WeBWorK::CourseEnvironment=HASH(0x5fa427816e20) [Tue Oct 22 09:10:49.764396 2024] (eval): Using authentication module WeBWorK::Authen::Shibboleth: WeBWorK::Authen::Shibboleth=HASH(0x5fa41fcd3008) [Tue Oct 22 09:10:49.764453 2024] (eval): We got a courseID from the route, now we can do some stuff: [Tue Oct 22 09:10:49.764487 2024] (eval): ...we can create a database object... [Tue Oct 22 09:10:49.781507 2024] (eval): (here's the DB handle: WeBWorK::DB=HASH(0x5fa41fcd3b78)) [Tue Oct 22 09:10:49.781575 2024] WeBWorK::Authen::verify: BEGIN VERIFY [Tue Oct 22 09:10:49.781609 2024] WeBWorK::Authen::do_verify: db ok [Tue Oct 22 09:10:49.781640 2024] WeBWorK::Authen::Shibboleth::get_credentials: Shib is on! [Tue Oct 22 09:10:49.781669 2024] WeBWorK::Authen::get_credentials: self is WeBWorK::Authen::Shibboleth=HASH(0x5fa41fcd3008) [Tue Oct 22 09:10:49.781719 2024] WeBWorK::Authen::get_credentials: credential source: "params", user: "thore.syyyyyf@xxx.de" key: "###UNDEF###" [Tue Oct 22 09:10:49.781744 2024] WeBWorK::Authen::do_verify: credentials ok [Tue Oct 22 09:10:49.782572 2024] WeBWorK::Authen::do_verify: check user ok [Tue Oct 22 09:10:49.782978 2024] WeBWorK::Authen::verify_normal_user: sessionExists='1' keyMatches='' timestampValid='1' [Tue Oct 22 09:10:49.783177 2024] WeBWorK::Authen::verify: END VERIFY [Tue Oct 22 09:10:49.783215 2024] WeBWorK::Authen::verify: result 0 [Tue Oct 22 09:10:49.783717 2024] WeBWorK::Authen::store_session: Deleting database session. [Tue Oct 22 09:10:51.813991 2024] (eval): ===> Begin WeBWorK::dispatch() <=== [Tue Oct 22 09:10:51.814096 2024] (eval): Hi, I'm the new dispatcher! [Tue Oct 22 09:10:51.814127 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:51.814152 2024] (eval): Okay, I got some basic information: [Tue Oct 22 09:10:51.814175 2024] (eval): The site location is /webwork2 [Tue Oct 22 09:10:51.814197 2024] (eval): The request method is POST [Tue Oct 22 09:10:51.814252 2024] (eval): The URI is /webwork2/test1/instructor/setmaker [Tue Oct 22 09:10:51.814276 2024] (eval): The argument string is user=thore.syyyyyf%40tuhh.de&effectiveUser=thore.syyyyyf%40tuhh.de&hidden_course_id=test1&hidden_language=en&browse_which=browse_opl&problem_seed=1234&first_shown=0&last_shown=-1&first_index=0&last_index=-1&total_probs=0&local_sets=&new_set_name=&library_is_basic=1&library_subject=1&library_chapter=&library_section=&includeOPL=on&includeOPL=0&lib_view=View+Problems&problem_displaymode=MathJax&original_displayMode=MathJax&max_shown=20 [Tue Oct 22 09:10:51.814299 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:51.814337 2024] (eval): The path is /test1/instructor/setmaker/ [Tue Oct 22 09:10:51.814373 2024] (eval): The current route is instructor_set_maker [Tue Oct 22 09:10:51.814398 2024] (eval): Here is some information about this route: [Tue Oct 22 09:10:51.814423 2024] (eval): The display module for this route is WeBWorK::ContentGenerator::Instructor::SetMaker [Tue Oct 22 09:10:51.814445 2024] (eval): This route has the following captures: [Tue Oct 22 09:10:51.814468 2024] (eval): controller => Instructor::SetMaker [Tue Oct 22 09:10:51.814489 2024] (eval): action => go [Tue Oct 22 09:10:51.814510 2024] (eval): courseID => test1 [Tue Oct 22 09:10:51.814532 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:51.814553 2024] (eval): Now we want to look at the parameters we got. [Tue Oct 22 09:10:51.814587 2024] (eval): The raw params: [Tue Oct 22 09:10:51.814713 2024] (eval): browse_which => "browse_opl" [Tue Oct 22 09:10:51.814740 2024] (eval): last_shown => "-1" [Tue Oct 22 09:10:51.814765 2024] (eval): problem_seed => "1234" [Tue Oct 22 09:10:51.814789 2024] (eval): first_shown => "0" [Tue Oct 22 09:10:51.814812 2024] (eval): library_is_basic => "1" [Tue Oct 22 09:10:51.814856 2024] (eval): library_section => "" [Tue Oct 22 09:10:51.814885 2024] (eval): library_subject => "1" [Tue Oct 22 09:10:51.814909 2024] (eval): local_sets => "" [Tue Oct 22 09:10:51.814931 2024] (eval): last_index => "-1" [Tue Oct 22 09:10:51.814953 2024] (eval): effectiveUser => "thore.syyyyyf@xxx.de" [Tue Oct 22 09:10:51.814976 2024] (eval): includeOPL => "on", "0" [Tue Oct 22 09:10:51.814998 2024] (eval): first_index => "0" [Tue Oct 22 09:10:51.815020 2024] (eval): max_shown => "20" [Tue Oct 22 09:10:51.815042 2024] (eval): hidden_language => "en" [Tue Oct 22 09:10:51.815064 2024] (eval): library_chapter => "" [Tue Oct 22 09:10:51.815086 2024] (eval): total_probs => "0" [Tue Oct 22 09:10:51.815108 2024] (eval): lib_view => "View Problems" [Tue Oct 22 09:10:51.815130 2024] (eval): problem_displaymode => "MathJax" [Tue Oct 22 09:10:51.815153 2024] (eval): user => "thore.syyyyyf@xxx.de" [Tue Oct 22 09:10:51.815175 2024] (eval): new_set_name => "" [Tue Oct 22 09:10:51.815197 2024] (eval): original_displayMode => "MathJax" [Tue Oct 22 09:10:51.815219 2024] (eval): hidden_course_id => "test1" [Tue Oct 22 09:10:51.815240 2024] (eval): -------------------------------------------------------------------------------- [Tue Oct 22 09:10:51.815265 2024] (eval): We need to get a course environment (with or without a courseID!) [Tue Oct 22 09:10:51.817809 2024] (eval): Here's the course environment: WeBWorK::CourseEnvironment=HASH(0x5fa427b8ae30) [Tue Oct 22 09:10:51.818068 2024] (eval): Using authentication module WeBWorK::Authen::Shibboleth: WeBWorK::Authen::Shibboleth=HASH(0x5fa427b8a2c0) [Tue Oct 22 09:10:51.818124 2024] (eval): We got a courseID from the route, now we can do some stuff: [Tue Oct 22 09:10:51.818166 2024] (eval): ...we can create a database object... [Tue Oct 22 09:10:51.823713 2024] (eval): (here's the DB handle: WeBWorK::DB=HASH(0x5fa427b00bc8)) [Tue Oct 22 09:10:51.823770 2024] WeBWorK::Authen::verify: BEGIN VERIFY [Tue Oct 22 09:10:51.823804 2024] WeBWorK::Authen::do_verify: db ok [Tue Oct 22 09:10:51.823869 2024] WeBWorK::Authen::Shibboleth::get_credentials: Shib is on! [Tue Oct 22 09:10:51.823912 2024] WeBWorK::Authen::get_credentials: self is WeBWorK::Authen::Shibboleth=HASH(0x5fa427b8a2c0) [Tue Oct 22 09:10:51.823956 2024] WeBWorK::Authen::get_credentials: credential source: "params", user: "thore.syyyyyf@xxx.de" key: "###UNDEF###" [Tue Oct 22 09:10:51.823979 2024] WeBWorK::Authen::do_verify: credentials ok [Tue Oct 22 09:10:51.824668 2024] WeBWorK::Authen::do_verify: check user ok [Tue Oct 22 09:10:51.825038 2024] WeBWorK::Authen::verify_normal_user: sessionExists='0' keyMatches='###UNDEF###' timestampValid='###UNDEF###' [Tue Oct 22 09:10:51.825165 2024] WeBWorK::Authen::verify: END VERIFY [Tue Oct 22 09:10:51.825198 2024] WeBWorK::Authen::verify: result 0 [Tue Oct 22 09:10:51.825230 2024] (eval): Bad news: authentication failed! [Tue Oct 22 09:10:51.825254 2024] (eval): Rendering WeBWorK::ContentGenerator::Login [Tue Oct 22 09:10:51.838356 2024] WeBWorK::Authen::store_session: Deleting database session.