Uploaded image for project: 'Moodle'
  1. Moodle
  2. MDL-62458

Remaining debugging in web server logs as of 3.5 release

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Open
    • Priority: Minor
    • Resolution: Unresolved
    • Affects Version/s: 3.5
    • Fix Version/s: None
    • Component/s: General, Lesson, SCORM, Workshop
    • Labels:
    • Affected Branches:
      MOODLE_35_STABLE

      Description

      Behat execution reveals a number of debugging messages sent to logs but not detected by behat (js...). This is a filter performed against a @javascript webeserver log:

      $ cat webserver | grep php7 | grep -v 'course.*view.php' | grep -v 'backup' | grep -v 'recyclebin' | more
       
      [Tue May 15 10:09:19.966614 2018] [php7:notice] [pid 504] [client 172.18.0.13:53008] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun2/mod/lesson/editpage.php
      [Tue May 15 10:09:20.230616 2018] [php7:notice] [pid 20] [client 172.18.0.13:53128] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun2/mod/lesson/editpage.php
      [Tue May 15 10:09:55.523855 2018] [php7:notice] [pid 519] [client 172.18.0.13:45096] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun2/mod/lesson/editpage.php
      [Tue May 15 10:09:55.777833 2018] [php7:notice] [pid 530] [client 172.18.0.13:45228] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun2/mod/lesson/editpage.php
      [Tue May 15 10:11:57.464723 2018] [php7:notice] [pid 526] [client 172.18.0.14:49540] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 27 of /mod/scorm/datamodel.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:11:57.582593 2018] [php7:notice] [pid 526] [client 172.18.0.14:49540] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 41 of /mod/scorm/prereqs.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:22:02.772396 2018] [php7:notice] [pid 570] [client 172.18.0.14:57696] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 27 of /mod/scorm/datamodel.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:22:02.961890 2018] [php7:notice] [pid 570] [client 172.18.0.14:57696] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 41 of /mod/scorm/prereqs.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:22:44.440609 2018] [php7:notice] [pid 635] [client 172.18.0.14:48538] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 27 of /mod/scorm/datamodel.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:22:44.567264 2018] [php7:notice] [pid 635] [client 172.18.0.14:48538] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 41 of /mod/scorm/prereqs.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:23:13.282657 2018] [php7:notice] [pid 635] [client 172.18.0.14:43820] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 27 of /mod/scorm/datamodel.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:23:13.406054 2018] [php7:notice] [pid 635] [client 172.18.0.14:43820] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 41 of /mod/scorm/prereqs.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:23:49.558317 2018] [php7:notice] [pid 666] [client 172.18.0.14:59352] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 27 of /mod/scorm/datamodel.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:23:49.680493 2018] [php7:notice] [pid 666] [client 172.18.0.14:59352] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 41 of /mod/scorm/prereqs.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php
      [Tue May 15 10:24:15.421851 2018] [php7:notice] [pid 643] [client 172.18.0.14:51286] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 37 of /mod/scorm/datamodel.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php?a=370000&currentorg=golf_sample_default_org&scoid=372002&newattempt=on
      [Tue May 15 10:24:15.614594 2018] [php7:notice] [pid 643] [client 172.18.0.14:51286] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 41 of /mod/scorm/prereqs.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php?a=370000&currentorg=golf_sample_default_org&scoid=372002&newattempt=on
      [Tue May 15 10:24:48.127837 2018] [php7:notice] [pid 608] [client 172.18.0.14:34922] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 37 of /mod/scorm/datamodel.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php?a=370000&currentorg=golf_sample_default_org&scoid=372001&newattempt=on
      [Tue May 15 10:24:48.242529 2018] [php7:notice] [pid 608] [client 172.18.0.14:34922] Default exception handler: Invalid course module ID Debug: \nError code: invalidcoursemodule\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 41 of /mod/scorm/prereqs.php: call to print_error()\n, referer: http://run8b23a742661b7534/behatrun3/mod/scorm/player.php?a=370000&currentorg=golf_sample_default_org&scoid=372001&newattempt=on
      [Tue May 15 10:36:41.956803 2018] [php7:notice] [pid 769] [client 172.18.0.14:60714] Debugging: Invalid get_string() identifier: 'note' or component 'core_note'. Perhaps you are missing $string['note'] = ''; in lang/en/core_note.php? in \n* line 349 of /lib/classes/string_manager_standard.php: call to debugging()\n* line 117 of /lib/external/externallib.php: call to core_string_manager_standard->get_string()\n* line 228 of /lib/externallib.php: call to core_external::get_string()\n* line 59 of /lib/ajax/service.php: call to external_api::call_external_function()\n* line 31 of /lib/ajax/service-nologin.php: call to require_once()\n, referer: http://run8b23a742661b7534/behatrun3/user/index.php?id=100000
      [Tue May 15 10:39:07.815894 2018] [php7:notice] [pid 734] [client 172.18.0.12:37090] Default exception handler: Course or activity not accessible. Debug: You are not logged in\nError code: requireloginerror\n* line 2606 of /lib/moodlelib.php: require_login_exception thrown\n* line 69 of /lib/editor/atto/autosave-ajax.php: call to require_login()\n, referer: http://run8b23a742661b7534/behatrun1/course/edit.php?id=103000
      [Tue May 15 10:39:40.413634 2018] [php7:notice] [pid 763] [client 172.18.0.12:54588] Default exception handler: Course or activity not accessible. Debug: You are not logged in\nError code: requireloginerror\n* line 2606 of /lib/moodlelib.php: require_login_exception thrown\n* line 69 of /lib/editor/atto/autosave-ajax.php: call to require_login()\n, referer: http://run8b23a742661b7534/behatrun1/course/edit.php?id=103000
      [Tue May 15 11:00:03.028575 2018] [php7:notice] [pid 971] [client 172.18.0.13:36962] Default exception handler: A required parameter (cmid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 28 of /mod/workshop/submission.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun2/mod/workshop/submission.php
      [Tue May 15 11:00:57.590144 2018] [php7:notice] [pid 955] [client 172.18.0.13:44012] Default exception handler: A required parameter (asid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 40 of /mod/workshop/assessment.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun2/mod/workshop/assessment.php
      [Tue May 15 11:03:52.468296 2018] [php7:notice] [pid 993] [client 172.18.0.14:44560] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun3/mod/lesson/editpage.php
      [Tue May 15 11:03:52.777033 2018] [php7:notice] [pid 906] [client 172.18.0.14:44730] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun3/mod/lesson/editpage.php
      [Tue May 15 11:04:04.232170 2018] [php7:notice] [pid 961] [client 172.18.0.14:52008] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun3/mod/lesson/editpage.php
      [Tue May 15 11:04:04.530263 2018] [php7:notice] [pid 982] [client 172.18.0.14:52138] Default exception handler: A required parameter (pageid) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/lesson/editpage.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun3/mod/lesson/editpage.php
      [Tue May 15 12:01:33.885469 2018] [php7:notice] [pid 1373] [client 172.18.0.12:32910] Debugging: Error: mdb->get_record() found more than one record! in \n* line 1583 of /lib/dml/moodle_database.php: call to debugging()\n* line 1543 of /lib/dml/moodle_database.php: call to moodle_database->get_record_sql()\n* line 1522 of /lib/dml/moodle_database.php: call to moodle_database->get_record_select()\n* line 1578 of /lib/filestorage/file_storage.php: call to moodle_database->get_record()\n* line 263 of /repository/repository_ajax.php: call to file_storage->create_file_from_reference()\n, referer: http://run8b23a742661b7534/behatrun1/course/modedit.php?add=folder&type=&course=103000&section=1&return=0&sr=0
      [Tue May 15 12:01:36.398579 2018] [php7:notice] [pid 1384] [client 172.18.0.12:33286] Default exception handler: A required parameter (id) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/folder/edit.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun1/mod/folder/edit.php
      [Tue May 15 12:01:49.851278 2018] [php7:notice] [pid 1362] [client 172.18.0.12:38146] Default exception handler: A required parameter (id) was missing Debug: \nError code: missingparam\n* line 482 of /lib/setuplib.php: moodle_exception thrown\n* line 573 of /lib/moodlelib.php: call to print_error()\n* line 31 of /mod/folder/edit.php: call to required_param()\n, referer: http://run8b23a742661b7534/behatrun1/mod/folder/edit.php
      [Tue May 15 12:01:57.028028 2018] [php7:notice] [pid 1379] [client 172.18.0.12:42156] Debugging: Error: mdb->get_record() found more than one record! in \n* line 1583 of /lib/dml/moodle_database.php: call to debugging()\n* line 1543 of /lib/dml/moodle_database.php: call to moodle_database->get_record_sql()\n* line 1522 of /lib/dml/moodle_database.php: call to moodle_database->get_record_select()\n* line 1578 of /lib/filestorage/file_storage.php: call to moodle_database->get_record()\n* line 248 of /repository/repository_ajax.php: call to file_storage->create_file_from_reference()\n, referer: http://run8b23a742661b7534/behatrun1/mod/folder/edit.php
      

      They aren't too many cases, but happen a number of times, ideally we should be clean of them.

      Note the logs above DO NOT include @~javascript (goutte) runs. We should equeally llok there.

      Ciao

      PS: Affect 3.5 and, possibly, older branches too (haven't verified).

        Attachments

          Activity

            People

            • Assignee:
              Unassigned
              Reporter:
              stronk7 Eloy Lafuente (stronk7)
              Participants:
              Component watchers:
              Jake Dallimore, Jun Pataleta, Ryan Wyllie, Adrian Greeve, Mihail Geshoski, Peter Dias, Damyon Wiese, Dan Marsden, Matteo Scaramuccia, Adrian Greeve, Mihail Geshoski, Peter Dias, Amaia Anabitarte, Carlos Escobedo, Sara Arjona (@sarjona), Víctor Déniz Falcón
            • Votes:
              1 Vote for this issue
              Watchers:
              3 Start watching this issue

              Dates

              • Created:
                Updated: