Skip to content Skip to main navigation Skip to footer

Performance worsening since 6.4.5

Welcome to QSM Support Forum. › Forums › QSM Pro Support › Performance worsening since 6.4.5

Viewing 12 posts - 1 through 12 (of 12 total)
  • Author
    Posts
  • May 11, 2020 at 11:46 am #3759
    Bob EastonBob Easton
    Participant

    The time it takes to show quiz results is getting longer since 6.4.5. I’m talking specifically about the time between pressing the “Check Answers” button and when results are displayed. Here are average times (6 repeated instances) for recent plugin versions:

    6.4.5 – 0.784 seconds
    6.4.8 – 1.344 seconds (1.71 times as long as 6.4.5)
    6.4.10 – 1.761 seconds (2.25 times as long as 6.4.5)

    Details of how I tested…
    Environment:
    Run a COPY of the our live site on a local development server powered by Laragon.
    Site copied from live to local via the Duplicator plugin.
    Laragon’s Apache server is version 2.4.35-win64-VC15.
    I modified Apache’s httpd.conf to provide milliseconds timestamp resolution.
    No other local sites are running, nor are any other local programs except the browser, currently Brave version Version 1.8.95 Chromium: 81.0.4044.138 (Official Build) (64-bit)
    Same plugins as live site, except a minification/optimization plugin is deactivated.

    Test process:
    Always use the same quiz post, the local equivalent of https://www.evaeaston.com/phrasal-verbs-test/.
    Note: this is a 12 question, randomized, multiple-choice quiz for non-logged-in users. It has NO email notices to reduce the ‘send email’ time variable.

    1. clear the browser cache
    2. refresh the “phrasel-verbs-test” post.
    3. scroll to the bottom of the quiz without answering any questions.
    4. press the “Check Answers” button and wait for the screen to repaint.
    5. open the httpd access.log
    6. copy out the last three lines.
    7. repeat steps 1-6 8 times.
    8. calculate time between first 2 lines of each test instance
    1st line is the POST from pressing the “Check Answers” button.
    2nd line is the GET for the facebook icon that is painted immediately after the quiz repaint.
    9. discard longest and shortest.
    10. average out the remaining.

    May 12, 2020 at 5:30 am #3797
    Kriti SharmaKriti Sharma
    Keymaster

    Thanks, Bob. For providing us the minute details. I am forwarding this issue to the development team and they will resolve this issue soon.

    Regards,
    Kriti

    May 12, 2020 at 8:14 pm #3847
    Bob EastonBob Easton
    Participant

    Thanks Kriti,
    Here is yet more information. Wanting to know “what is going on inside?”, I added logging messages to some of the QSM modules in both 6.4.5 and 6.4.10. I used the same local server setup described earlier. This time with WP-DEBUG set to TRUE and with a log_me routine that writes log messages to the debug log. Since the debug log had a timestamp resolution that goes only to the nearest second, I prefaced each of the messages with a microseconds timestamp. The logging routine in my theme’s functions.php looks like this:

    function log_me($message) {
    if ( WP_DEBUG === true ) {
        if ( is_array($message) || is_object($message) ) {
            error_log( print_r($message, true) );
        } else {
            error_log( gettimeofday()['usec'] . ' ' . $message );  /* microseconds added */
        }
    }
}

    I placed log_me messages immediately after each of the ‘public function….’ lines in qmn-quiz-manager and qsm-emails modules, and one extra just before the $return_array for the results page redirect … about line #1125.

    Then, I ran 6 tests each as described earlier.

    NOTE WELL: There are 2 areas of interest.
    The first is immediately after the POST action that is started by pressing the “Check Answers” button. Examine the time between the post and the first log message inside qmn-quiz-manger, the ajaz_submit_results routine. I’ve noted the time intervals in the following log files.
    Look for “n.nnn since POST.”

    Note that with 6.4.5, that time interval ranges from 0.285 seconds to 0.409 seconds, and with 6.4.10 it ranges from 1.108 to 1.351 seconds,
    substantially longer. I did not dig deeper to find the code between those 2 events, but it seems a good place to look.

    The second place to note is the time between “results logged in db” and the fetch of the facebook icon. There is about 3 fourths of a second of email processing time there, even though I have no emails specified. That time delays rendering the results on screen. Maybe it can be moved after “prep data for front end?”

    Notice also, 2 images that help clarify where information is in the following log data.

    THANKS again for your interest.

    Log results follow…
    6.4.10 — w/o emails

    127.0.0.1 – – 10/May/2020:07:27:18-903 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9850
    [10-May-2020 11:27:20 UTC] 446525 qmn-quiz-manager ajax_submit_results — 1.543 since POST
    [10-May-2020 11:27:20 UTC] 448048 qmn-quiz-manager submit_results
    [10-May-2020 11:27:20 UTC] 448911 qmn-quiz-manager get_user_ip
    [10-May-2020 11:27:20 UTC] 449786 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:27:20 UTC] 452729 qmn-quiz-manager check_answers
    [10-May-2020 11:27:20 UTC] 454780 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 455864 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 456914 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 457957 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 459009 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 460117 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 461171 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 462314 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 463417 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 464470 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 465517 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 466617 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:27:20 UTC] 467720 qmn-quiz-manager scoring done
    [10-May-2020 11:27:20 UTC] 468576 qmn-quiz-manager check_comment_section
    [10-May-2020 11:27:20 UTC] 469489 qmn-quiz-manager display_results_text
    [10-May-2020 11:27:20 UTC] 470399 qsm-results-pages generate_pages
    [10-May-2020 11:27:20 UTC] 471299 qsm-results-pages load_pages
    [10-May-2020 11:27:20 UTC] 486079 qmn-quiz-manager display_social
    [10-May-2020 11:27:20 UTC] 490153 qmn-quiz-manager results logged in db
    [10-May-2020 11:27:20 UTC] 491080 qsm-emails send_emails
    [10-May-2020 11:27:20 UTC] 491928 qsm-emails load_emails
    [10-May-2020 11:27:21 UTC] 251028 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:27:21-279 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:27:21-280 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:30:21-714 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9850
    [10-May-2020 11:30:22 UTC] 822723 qmn-quiz-manager ajax_submit_results — 1.108 since POST
    [10-May-2020 11:30:22 UTC] 827244 qmn-quiz-manager submit_results
    [10-May-2020 11:30:22 UTC] 828042 qmn-quiz-manager get_user_ip
    [10-May-2020 11:30:22 UTC] 828847 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:30:22 UTC] 831607 qmn-quiz-manager check_answers
    [10-May-2020 11:30:22 UTC] 833556 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 834551 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 835516 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 836477 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 837435 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 838395 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 839355 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 840329 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 841309 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 842344 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 843351 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 844316 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:30:22 UTC] 845281 qmn-quiz-manager scoring done
    [10-May-2020 11:30:22 UTC] 846063 qmn-quiz-manager check_comment_section
    [10-May-2020 11:30:22 UTC] 846829 qmn-quiz-manager display_results_text
    [10-May-2020 11:30:22 UTC] 847600 qsm-results-pages generate_pages
    [10-May-2020 11:30:22 UTC] 848367 qsm-results-pages load_pages
    [10-May-2020 11:30:22 UTC] 854159 qmn-quiz-manager display_social
    [10-May-2020 11:30:22 UTC] 857215 qmn-quiz-manager results logged in db
    [10-May-2020 11:30:22 UTC] 858010 qsm-emails send_emails
    [10-May-2020 11:30:22 UTC] 858794 qsm-emails load_emails
    [10-May-2020 11:30:22 UTC] 894284 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:30:22-922 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:30:22-922 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:31:52-822 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9850
    [10-May-2020 11:31:54 UTC] 173939 qmn-quiz-manager ajax_submit_results — 1.351 since POST
    [10-May-2020 11:31:54 UTC] 180636 qmn-quiz-manager submit_results
    [10-May-2020 11:31:54 UTC] 182665 qmn-quiz-manager get_user_ip
    [10-May-2020 11:31:54 UTC] 184443 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:31:54 UTC] 188513 qmn-quiz-manager check_answers
    [10-May-2020 11:31:54 UTC] 191875 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 193698 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 195445 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 197172 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 198864 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 200550 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 202316 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 203821 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 205332 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 206784 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 208229 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 209674 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:31:54 UTC] 210921 qmn-quiz-manager scoring done
    [10-May-2020 11:31:54 UTC] 211982 qmn-quiz-manager check_comment_section
    [10-May-2020 11:31:54 UTC] 212982 qmn-quiz-manager display_results_text
    [10-May-2020 11:31:54 UTC] 213976 qsm-results-pages generate_pages
    [10-May-2020 11:31:54 UTC] 214972 qsm-results-pages load_pages
    [10-May-2020 11:31:54 UTC] 221070 qmn-quiz-manager display_social
    [10-May-2020 11:31:54 UTC] 224893 qmn-quiz-manager results logged in db
    [10-May-2020 11:31:54 UTC] 225802 qsm-emails send_emails
    [10-May-2020 11:31:54 UTC] 226692 qsm-emails load_emails
    [10-May-2020 11:31:54 UTC] 268052 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:31:54-295 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:31:54-295 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:33:59-551 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9850
    [10-May-2020 11:34:01 UTC] 81355 qmn-quiz-manager ajax_submit_results — 1.262 since POST
    [10-May-2020 11:34:01 UTC] 85848 qmn-quiz-manager submit_results
    [10-May-2020 11:34:01 UTC] 86704 qmn-quiz-manager get_user_ip
    [10-May-2020 11:34:01 UTC] 87522 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:34:01 UTC] 90262 qmn-quiz-manager check_answers
    [10-May-2020 11:34:01 UTC] 92109 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 93574 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 94651 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 95671 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 96677 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 97684 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 98638 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 99637 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 100640 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 101663 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 102666 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 103648 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:34:01 UTC] 104610 qmn-quiz-manager scoring done
    [10-May-2020 11:34:01 UTC] 105460 qmn-quiz-manager check_comment_section
    [10-May-2020 11:34:01 UTC] 106292 qmn-quiz-manager display_results_text
    [10-May-2020 11:34:01 UTC] 107125 qsm-results-pages generate_pages
    [10-May-2020 11:34:01 UTC] 107953 qsm-results-pages load_pages
    [10-May-2020 11:34:01 UTC] 113195 qmn-quiz-manager display_social
    [10-May-2020 11:34:01 UTC] 116353 qmn-quiz-manager results logged in db
    [10-May-2020 11:34:01 UTC] 117198 qsm-emails send_emails
    [10-May-2020 11:34:01 UTC] 118032 qsm-emails load_emails
    127.0.0.1 – – 10/May/2020:07:34:01-180 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:34:01-181 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:35:51-550 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9850
    [10-May-2020 11:35:52 UTC] 489989 qmn-quiz-manager ajax_submit_results — 0.939 since POST
    [10-May-2020 11:35:52 UTC] 495172 qmn-quiz-manager submit_results
    [10-May-2020 11:35:52 UTC] 496231 qmn-quiz-manager get_user_ip
    [10-May-2020 11:35:52 UTC] 497149 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:35:52 UTC] 500142 qmn-quiz-manager check_answers
    [10-May-2020 11:35:52 UTC] 502383 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 503621 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 504740 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 505850 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 506949 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 508078 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 509185 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 510578 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 511938 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 513121 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 514303 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 515457 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:35:52 UTC] 516599 qmn-quiz-manager scoring done
    [10-May-2020 11:35:52 UTC] 517529 qmn-quiz-manager check_comment_section
    [10-May-2020 11:35:52 UTC] 518424 qmn-quiz-manager display_results_text
    [10-May-2020 11:35:52 UTC] 519407 qsm-results-pages generate_pages
    [10-May-2020 11:35:52 UTC] 520326 qsm-results-pages load_pages
    [10-May-2020 11:35:52 UTC] 526169 qmn-quiz-manager display_social
    [10-May-2020 11:35:52 UTC] 530840 qmn-quiz-manager results logged in db
    [10-May-2020 11:35:52 UTC] 531871 qsm-emails send_emails
    [10-May-2020 11:35:52 UTC] 532826 qsm-emails load_emails
    [10-May-2020 11:35:52 UTC] 572799 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:35:52-601 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:35:52-601 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:37:47-608 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9850
    [10-May-2020 11:37:48 UTC] 858448 qmn-quiz-manager ajax_submit_results — 1.250 since POST
    [10-May-2020 11:37:48 UTC] 863107 qmn-quiz-manager submit_results
    [10-May-2020 11:37:48 UTC] 863958 qmn-quiz-manager get_user_ip
    [10-May-2020 11:37:48 UTC] 864793 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:37:48 UTC] 870808 qmn-quiz-manager check_answers
    [10-May-2020 11:37:48 UTC] 872775 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 873849 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 874842 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 875838 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 876835 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 877871 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 878920 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 879910 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 880897 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 881890 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 883029 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 884075 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:37:48 UTC] 885128 qmn-quiz-manager scoring done
    [10-May-2020 11:37:48 UTC] 885991 qmn-quiz-manager check_comment_section
    [10-May-2020 11:37:48 UTC] 886790 qmn-quiz-manager display_results_text
    [10-May-2020 11:37:48 UTC] 887573 qsm-results-pages generate_pages
    [10-May-2020 11:37:48 UTC] 888375 qsm-results-pages load_pages
    [10-May-2020 11:37:48 UTC] 894130 qmn-quiz-manager display_social
    [10-May-2020 11:37:48 UTC] 897363 qmn-quiz-manager results logged in db
    [10-May-2020 11:37:48 UTC] 898231 qsm-emails send_emails
    [10-May-2020 11:37:48 UTC] 899024 qsm-emails load_emails
    [10-May-2020 11:37:48 UTC] 934740 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:37:48-962 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:37:48-963 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    6.4.5 — w/o emails

    127.0.0.1 – – 10/May/2020:07:42:12-290 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9488
    [10-May-2020 11:42:12 UTC] 699968 qmn-quiz-manager ajax_submit_results — 0.409 since POST
    [10-May-2020 11:42:12 UTC] 701495 qmn-quiz-manager submit_results
    [10-May-2020 11:42:12 UTC] 702368 qmn-quiz-manager get_user_ip
    [10-May-2020 11:42:12 UTC] 703277 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:42:12 UTC] 706132 qmn-quiz-manager check_answers
    [10-May-2020 11:42:12 UTC] 708105 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 709348 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 710538 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 711794 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 712984 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 714234 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 715477 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 716728 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 718043 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 719330 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 720612 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 721889 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:42:12 UTC] 723343 qmn-quiz-manager scoring done
    [10-May-2020 11:42:12 UTC] 724493 qmn-quiz-manager check_comment_section
    [10-May-2020 11:42:12 UTC] 725568 qmn-quiz-manager display_results_text
    [10-May-2020 11:42:12 UTC] 726591 qsm-results-pages generate_pages
    [10-May-2020 11:42:12 UTC] 727690 qsm-results-pages load_pages
    [10-May-2020 11:42:12 UTC] 731700 qmn-quiz-manager display_social
    [10-May-2020 11:42:12 UTC] 735973 qmn-quiz-manager results logged in db
    [10-May-2020 11:42:12 UTC] 737046 qsm-emails send_emails
    [10-May-2020 11:42:12 UTC] 738149 qsm-emails load_emails
    [10-May-2020 11:42:12 UTC] 739439 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:42:12-769 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:42:12-770 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:44:23-241 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9488
    [10-May-2020 11:44:23 UTC] 530003 qmn-quiz-manager ajax_submit_results — 0.289 since POST
    [10-May-2020 11:44:23 UTC] 535117 qmn-quiz-manager submit_results
    [10-May-2020 11:44:23 UTC] 535916 qmn-quiz-manager get_user_ip
    [10-May-2020 11:44:23 UTC] 536990 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:44:23 UTC] 540517 qmn-quiz-manager check_answers
    [10-May-2020 11:44:23 UTC] 542546 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 543564 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 544529 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 545488 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 546452 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 547410 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 548343 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 549301 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 550262 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 551226 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 552156 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 553322 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:44:23 UTC] 555026 qmn-quiz-manager scoring done
    [10-May-2020 11:44:23 UTC] 555994 qmn-quiz-manager check_comment_section
    [10-May-2020 11:44:23 UTC] 556844 qmn-quiz-manager display_results_text
    [10-May-2020 11:44:23 UTC] 557636 qsm-results-pages generate_pages
    [10-May-2020 11:44:23 UTC] 558420 qsm-results-pages load_pages
    [10-May-2020 11:44:23 UTC] 562054 qmn-quiz-manager display_social
    [10-May-2020 11:44:23 UTC] 565685 qmn-quiz-manager results logged in db
    [10-May-2020 11:44:23 UTC] 566538 qsm-emails send_emails
    [10-May-2020 11:44:23 UTC] 567328 qsm-emails load_emails
    [10-May-2020 11:44:23 UTC] 568301 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:44:23-593 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:44:23-593 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:45:49-262 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9488
    [10-May-2020 11:45:49 UTC] 802245 qmn-quiz-manager ajax_submit_results — 0.540 since POST
    [10-May-2020 11:45:49 UTC] 813018 qmn-quiz-manager submit_results
    [10-May-2020 11:45:49 UTC] 816181 qmn-quiz-manager get_user_ip
    [10-May-2020 11:45:49 UTC] 819344 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:45:49 UTC] 826335 qmn-quiz-manager check_answers
    [10-May-2020 11:45:49 UTC] 834070 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 837959 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 842401 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 846361 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 850145 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 853949 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 858267 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 862433 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 866300 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 870070 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 874115 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 878389 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:45:49 UTC] 882176 qmn-quiz-manager scoring done
    [10-May-2020 11:45:49 UTC] 885293 qmn-quiz-manager check_comment_section
    [10-May-2020 11:45:49 UTC] 888353 qmn-quiz-manager display_results_text
    [10-May-2020 11:45:49 UTC] 891562 qsm-results-pages generate_pages
    [10-May-2020 11:45:49 UTC] 894861 qsm-results-pages load_pages
    [10-May-2020 11:45:49 UTC] 907280 qmn-quiz-manager display_social
    [10-May-2020 11:45:49 UTC] 915309 qmn-quiz-manager results logged in db
    [10-May-2020 11:45:49 UTC] 917491 qsm-emails send_emails
    [10-May-2020 11:45:49 UTC] 919603 qsm-emails load_emails
    [10-May-2020 11:45:49 UTC] 922166 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:45:49-948 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:45:49-949 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:47:17-224 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9488
    [10-May-2020 11:47:17 UTC] 510434 qmn-quiz-manager ajax_submit_results — 0.286 since POST
    [10-May-2020 11:47:17 UTC] 516053 qmn-quiz-manager submit_results
    [10-May-2020 11:47:17 UTC] 517052 qmn-quiz-manager get_user_ip
    [10-May-2020 11:47:17 UTC] 518034 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:47:17 UTC] 520971 qmn-quiz-manager check_answers
    [10-May-2020 11:47:17 UTC] 523155 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 524338 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 525452 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 526550 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 527898 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 529242 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 530687 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 531895 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 533079 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 534277 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 535422 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 536566 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:47:17 UTC] 537654 qmn-quiz-manager scoring done
    [10-May-2020 11:47:17 UTC] 538601 qmn-quiz-manager check_comment_section
    [10-May-2020 11:47:17 UTC] 539522 qmn-quiz-manager display_results_text
    [10-May-2020 11:47:17 UTC] 540401 qsm-results-pages generate_pages
    [10-May-2020 11:47:17 UTC] 541374 qsm-results-pages load_pages
    [10-May-2020 11:47:17 UTC] 545026 qmn-quiz-manager display_social
    [10-May-2020 11:47:17 UTC] 549425 qmn-quiz-manager results logged in db
    [10-May-2020 11:47:17 UTC] 550452 qsm-emails send_emails
    [10-May-2020 11:47:17 UTC] 551443 qsm-emails load_emails
    [10-May-2020 11:47:17 UTC] 552654 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:47:17-577 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:47:17-577 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:49:19-259 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9488
    [10-May-2020 11:49:19 UTC] 558887 qmn-quiz-manager ajax_submit_results — 0.299 since POST
    [10-May-2020 11:49:19 UTC] 563880 qmn-quiz-manager submit_results
    [10-May-2020 11:49:19 UTC] 564648 qmn-quiz-manager get_user_ip
    [10-May-2020 11:49:19 UTC] 565559 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:49:19 UTC] 568723 qmn-quiz-manager check_answers
    [10-May-2020 11:49:19 UTC] 570796 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 571850 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 572838 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 573803 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 574836 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 575849 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 576824 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 577797 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 578730 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 579637 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 580592 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 581521 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:49:19 UTC] 582853 qmn-quiz-manager scoring done
    [10-May-2020 11:49:19 UTC] 584016 qmn-quiz-manager check_comment_section
    [10-May-2020 11:49:19 UTC] 584898 qmn-quiz-manager display_results_text
    [10-May-2020 11:49:19 UTC] 585691 qsm-results-pages generate_pages
    [10-May-2020 11:49:19 UTC] 586531 qsm-results-pages load_pages
    [10-May-2020 11:49:19 UTC] 589702 qmn-quiz-manager display_social
    [10-May-2020 11:49:19 UTC] 593325 qmn-quiz-manager results logged in db
    [10-May-2020 11:49:19 UTC] 594181 qsm-emails send_emails
    [10-May-2020 11:49:19 UTC] 594970 qsm-emails load_emails
    [10-May-2020 11:49:19 UTC] 596010 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:49:19-620 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:49:19-621 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    127.0.0.1 – – 10/May/2020:07:50:49-961 “POST /wp-admin/admin-ajax.php HTTP/1.1” 200 9488
    [10-May-2020 11:50:50 UTC] 246870 qmn-quiz-manager ajax_submit_results — 0.285 since POST
    [10-May-2020 11:50:50 UTC] 251948 qmn-quiz-manager submit_results
    [10-May-2020 11:50:50 UTC] 252775 qmn-quiz-manager get_user_ip
    [10-May-2020 11:50:50 UTC] 253595 qmn-quiz-manager qmn_update_taken
    [10-May-2020 11:50:50 UTC] 256418 qmn-quiz-manager check_answers
    [10-May-2020 11:50:50 UTC] 259112 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 260187 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 261166 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 262174 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 263240 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 264256 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 265183 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 266105 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 267068 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 268074 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 269033 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 270006 qmn-quiz-manager grade question 1st loop
    [10-May-2020 11:50:50 UTC] 270998 qmn-quiz-manager scoring done
    [10-May-2020 11:50:50 UTC] 271782 qmn-quiz-manager check_comment_section
    [10-May-2020 11:50:50 UTC] 272694 qmn-quiz-manager display_results_text
    [10-May-2020 11:50:50 UTC] 273947 qsm-results-pages generate_pages
    [10-May-2020 11:50:50 UTC] 274914 qsm-results-pages load_pages
    [10-May-2020 11:50:50 UTC] 278104 qmn-quiz-manager display_social
    [10-May-2020 11:50:50 UTC] 281641 qmn-quiz-manager results logged in db
    [10-May-2020 11:50:50 UTC] 282491 qsm-emails send_emails
    [10-May-2020 11:50:50 UTC] 283371 qsm-emails load_emails
    [10-May-2020 11:50:50 UTC] 284344 qmn-quiz-manager prep data for front end
    127.0.0.1 – – 10/May/2020:07:50:50-306 “GET /wp-content/plugins/quiz-master-next/assets/facebook.png HTTP/1.1” 200 416
    127.0.0.1 – – 10/May/2020:07:50:50-307 “GET /wp-content/plugins/quiz-master-next/assets/twitter.png HTTP/1.1” 200 584

    Attachments:
    You must be logged in to view attached files.
    May 13, 2020 at 11:35 pm #3891
    Kriti SharmaKriti Sharma
    Keymaster

    Hi Bob,

    Our development team is looking into the issue. I will get back to you as soon as I hear back from them.

    Regards,
    Kriti

    May 25, 2020 at 10:08 pm #4221
    Bob EastonBob Easton
    Participant

    Seeing that the changelog for 6.4.11 mentions reducing queries, I ran some tests again. This time, exactly as described in the May 11, 11:46 post in this thread.

    6.4.5 averages 0.784 seconds
    6.4.10 averages 1.761 seconds – 2.24 times slower than 6.4.5
    6.4.11 averages 1.134 seconds – 1.44 times slower than 6.4.5 Better! … but not enough.

    BTW, these are optimal, in a very controlled environment. When live online, mixed with all the rest of the internet chaos, they are significantly slower. And every second of delay causes 7% loss of audience.

    Better. Is the team still looking to improve more?

    May 26, 2020 at 6:12 am #4229
    Kriti SharmaKriti Sharma
    Keymaster

    Hi Bob,

    Yes, our team is still working on to improve the plugin. Hopefully we will fix the issue in the next plugin update.

    Kind regards,
    Kriti

    June 24, 2020 at 1:20 pm #4732
    Bob EastonBob Easton
    Participant

    6.4.12 is slower than ever in grading answers. What a big disappointment!

    It is now 3.17 times slower than 6.4.5. (testing technique is the same as before)
    The average time to return grading for a 12 question multiple choice quiz is now 2.493 seconds. A typical visitor isn’t going to wait much longer than a second when expecting the next page … especially if there is absolutely no sign of activity.

    If the development plan is to continue adding features (they all eat time) without focusing on performance, then maybe it is time to add an hourglass, activity spinner, or progress bar while calculating answers.

    Keeping my live site at 6.4.5.

    June 24, 2020 at 7:32 pm #4733
    Kriti SharmaKriti Sharma
    Keymaster

    Hi Bob,

    I will forward your response to the development team.

    Kind regards,
    Kriti

    July 26, 2020 at 8:20 pm #5087
    Bob EastonBob Easton
    Participant

    Version 7.0.0 is now 5 times slower than 6.4.5 when evaluating quiz results. It keeps getting worse with every update. Below are the times for various levels, all tested exactly as described above, with exactly the same quiz, on a very controlled local server environment.

    Time to calculate answers for a 12 question multiple choice quiz … with NO email results.
    6.4.5 averages 0.784 seconds
    6.4.10 averages 1.761 seconds – 2.24 times slower than 6.4.5
    6.4.11 averages 1.134 seconds – 1.44 times slower than 6.4.5 — Better! – some db calls reduced
    6.4.12 averages 2.493 seconds – 3.17 times slower than 6.4.5
    7.0.0 averages 3.926 seconds – 5.00 times slower than 6.4.5

    Two good things changed with this version:
    1. Finally, a spinner has been added to give people some sense that something is actually happening. That might persuade a few not to abandon the site.
    2. I see a second POST activity for an email action. Perhaps emailing has been taken out of the answer loop and deferred? I haven’t confirmed that with testing yet.

    July 27, 2020 at 5:09 pm #5088
    Kriti SharmaKriti Sharma
    Keymaster

    Thanks Bob,for sharing the information with us. I have informed the development team regarding your response.

    Kind regards,
    Kriti

    August 10, 2020 at 12:02 pm #5102
    Bob EastonBob Easton
    Participant

    Results performance update for 7.01…

    Time to calculate answers for a 12 question multiple choice quiz … with NO email results.
    6.4.5 averages 0.784 seconds
    6.4.10 averages 1.761 seconds – 2.24 times slower than 6.4.5
    6.4.11 averages 1.134 seconds – 1.44 times slower than 6.4.5 — Better! – some db calls reduced
    6.4.12 averages 2.493 seconds – 3.17 times slower than 6.4.5
    7.0.0 averages 3.926 seconds – 5.00 times slower than 6.4.5
    7.0.1 averages 3.514 seconds – 4.48 times slower than 6.4.5

    October 11, 2020 at 10:40 pm #5114
    Bob EastonBob Easton
    Participant

    Updates for recent versions. Testing is exactly as described at the top of this thread.

    * 7.0.2 – averages 3.454 seconds – 4.41 times slower than 6.4.5
    * 7.1.1 – averages 3.400 seconds – 4.34 times slower than 6.4.5
    * 7.1.2 – averages 3.318 seconds – 4.23 times slower than 6.4.5
    * 7.1.3 – averages 3.894 seconds – 4.966 times slower than 6.4.5
    * 7.1.4 – averages 4.619 seconds – 5.891 times slower than 6.4.5

    While the development team acted on a couple of suggestions (deferring email sending until after posting results, and showing a progress spinner while calculating), it is obvious that they have done very little else to improve response time.

  • Author
    Posts
Viewing 12 posts - 1 through 12 (of 12 total)
  • You must be logged in to reply to this topic.