Difference between revisions of "WeBWorK performance"

From WeBWorK_wiki
Jump to navigation Jump to search
 
(13 intermediate revisions by 4 users not shown)
Line 1: Line 1:
 
== The timing log ==
 
== The timing log ==
   
The <code>timing_log_performance.pl</code> is available in <code>webwork2/bin</code> and parses the timing log data found in <code>webwork2/logs/timing.log</code>.
+
The <code>timing_log_check.pl</code> is available in <code>/opt/webwork/webwork2/bin</code> and parses the timing log data found in <code>/opt/webwork/webwork2/logs/timing.log</code>.
  +
Note that this only looks at the current timing.log. If the timing.log is being rotated, modify the script to parse data in older logs which may be compressed.
  +
Older logs are also found in <code>/opt/webwork/webwork2/logs/</code>.
   
 
To run the script, <code>cd</code> to the WeBWorK logs directory (usually <code>/opt/webwork/webwork2/logs</code>)
 
To run the script, <code>cd</code> to the WeBWorK logs directory (usually <code>/opt/webwork/webwork2/logs</code>)
Line 8: Line 8:
   
 
=== Data ===
 
=== Data ===
  +
  +
* From the <code>courses1.webwork.maa.org</code> timing log:
  +
  +
Data from January 6, 2014 to September 4, 2014
  +
  +
count = 12479615
  +
  +
under 0.1 seconds = 6202643: 49.7%
  +
  +
between 0.1 and 0.2 seconds = 4575120: 36.7%
  +
  +
between 0.2 and 0.5 seconds = 1495042: 12%
  +
  +
between 0.5 and 1.0 seconds = 115930: 0.9%
  +
  +
between 1.0 and 2.0 seconds = 56915: 0.5%
  +
  +
between 2.0 and 3.0 seconds = 15674: 0.1%
  +
  +
between 3.0 and 4.0 seconds = 5789: 0%
  +
  +
between 4.0 and 5.0 seconds = 3230: 0%
  +
  +
between 5.0 and 10.0 seconds = 5214: 0%
  +
  +
over 10.0 seconds = 4058: 0%
  +
  +
non valid response = 0: 0%
  +
  +
   
 
* From the <code>courses.webwork.maa.org</code> timing log:
 
* From the <code>courses.webwork.maa.org</code> timing log:
  +
  +
Data from September 2012
  +
  +
count = 2612898
  +
  +
under 0.1 seconds = 1807850: 69.2%
  +
  +
between 0.1 and 0.2 seconds = 414769: 15.9%
  +
  +
between 0.2 and 0.5 seconds = 356358: 13.6%
  +
  +
between 0.5 and 1.0 seconds = 15600: 0.6%
  +
  +
between 1.0 and 2.0 seconds = 12624: 0.5%
  +
  +
between 2.0 and 3.0 seconds = 3912: 0.1%
  +
  +
between 3.0 and 4.0 seconds = 781: 0%
  +
  +
between 4.0 and 5.0 seconds = 361: 0%
  +
  +
between 5.0 and 10.0 seconds = 429: 0%
  +
  +
over 10.0 seconds = 214: 0%
  +
  +
non valid response = 0: 0%
  +
  +
  +
 
Data from July 2010 - August 2011
 
Data from July 2010 - August 2011
   
Line 96: Line 155:
 
non valid response = 0: 0%
 
non valid response = 0: 0%
   
  +
=== webwork.math.missouri.edu ===
   
* From the Missouri server 32 bit freebsd 7.2 vm with 4GB of ram serving about 1400 students per semester from May 5 2010 to Oct 10 2011:
 
  +
==== May 5, 2010 to Oct 10, 2011 ====
  +
  +
  +
Server: 32 bit freebsd 7.2 vm with 4GB of ram serving 1200-1400 students per semester
   
 
count = 4664624
 
count = 4664624
  +
 
under 0.1 seconds = 3696211: 79.2%
 
under 0.1 seconds = 3696211: 79.2%
   
Line 124: Line 188:
   
 
'''Note''': This server had two "out of memory" events which brought the server down due to a freebsd kernel setting that we should have tweaked but didn't until we understood the problem. This isn't a concern with the linux kernel or 64bit freebsd machines. These two events probably account for most of the requests on the high end.
 
'''Note''': This server had two "out of memory" events which brought the server down due to a freebsd kernel setting that we should have tweaked but didn't until we understood the problem. This isn't a concern with the linux kernel or 64bit freebsd machines. These two events probably account for most of the requests on the high end.
  +
  +
==== February 02, 2012 - May 22, 2012 ====
  +
Server: FreeBSD 8.2-RELEASE-p2 amd64, Intel Xeon Processor E5520, 16GB RAM
  +
  +
About 2300 users in SP12 Semester
  +
  +
count = 1438276
  +
  +
under 0.1 seconds = 1007856: 70.1%
  +
  +
between 0.1 and 0.2 seconds = 217355: 15.1%
  +
  +
between 0.2 and 0.5 seconds = 179686: 12.5%
  +
  +
between 0.5 and 1.0 seconds = 25465: 1.8%
  +
  +
between 1.0 and 2.0 seconds = 5565: 0.4%
  +
  +
between 2.0 and 3.0 seconds = 902: 0.1%
  +
  +
between 3.0 and 4.0 seconds = 375: 0%
  +
  +
between 4.0 and 5.0 seconds = 227: 0%
  +
  +
between 5.0 and 10.0 seconds = 345: 0%
  +
  +
over 10.0 seconds = 500: 0%
  +
  +
non valid response = 0: 0%
   
 
== Results from load monitoring software ==
 
== Results from load monitoring software ==
Line 133: Line 226:
 
== External Links ==
 
== External Links ==
 
* http://en.wikipedia.org/wiki/RRDtool
 
* http://en.wikipedia.org/wiki/RRDtool
 
  +
* [http://www.math.dartmouth.edu/munin/dartmouth.edu/webwork.dartmouth.edu/index.html Comments] on Munin from Saruna Burdulis at Dartmouth. [http://www.math.dartmouth.edu/munin/dartmouth.edu/webwork.dartmouth.edu/index.html image]
  +
* http://www.webalizer.org/ - Another monitoring option
 
[[Category:Administrators]]
 
[[Category:Administrators]]

Latest revision as of 15:30, 5 May 2020

The timing log

The timing_log_check.pl is available in /opt/webwork/webwork2/bin and parses the timing log data found in /opt/webwork/webwork2/logs/timing.log. Note that this only looks at the current timing.log. If the timing.log is being rotated, modify the script to parse data in older logs which may be compressed. Older logs are also found in /opt/webwork/webwork2/logs/.

To run the script, cd to the WeBWorK logs directory (usually /opt/webwork/webwork2/logs) and enter the command: timing_log_check.pl


Data

  • From the courses1.webwork.maa.org timing log:

Data from January 6, 2014 to September 4, 2014

count = 12479615

under 0.1 seconds = 6202643: 49.7%

between 0.1 and 0.2 seconds = 4575120: 36.7%

between 0.2 and 0.5 seconds = 1495042: 12%

between 0.5 and 1.0 seconds = 115930: 0.9%

between 1.0 and 2.0 seconds = 56915: 0.5%

between 2.0 and 3.0 seconds = 15674: 0.1%

between 3.0 and 4.0 seconds = 5789: 0%

between 4.0 and 5.0 seconds = 3230: 0%

between 5.0 and 10.0 seconds = 5214: 0%

over 10.0 seconds = 4058: 0%

non valid response = 0: 0%


  • From the courses.webwork.maa.org timing log:

Data from September 2012

count = 2612898

under 0.1 seconds = 1807850: 69.2%

between 0.1 and 0.2 seconds = 414769: 15.9%

between 0.2 and 0.5 seconds = 356358: 13.6%

between 0.5 and 1.0 seconds = 15600: 0.6%

between 1.0 and 2.0 seconds = 12624: 0.5%

between 2.0 and 3.0 seconds = 3912: 0.1%

between 3.0 and 4.0 seconds = 781: 0%

between 4.0 and 5.0 seconds = 361: 0%

between 5.0 and 10.0 seconds = 429: 0%

over 10.0 seconds = 214: 0%

non valid response = 0: 0%


Data from July 2010 - August 2011

count = 13427151

under 0.1 seconds = 7999616: 59.6%

between 0.1 and 0.2 seconds = 2864257: 21.3%

between 0.2 and 0.5 seconds = 1955285: 14.6%

between 0.5 and 1.0 seconds = 297585: 2.2%

between 1.0 and 2.0 seconds = 135471: 1%

between 2.0 and 3.0 seconds = 45002: 0.3%

between 3.0 and 4.0 seconds = 23265: 0.2%

between 4.0 and 5.0 seconds = 13663: 0.1%

between 5.0 and 10.0 seconds = 30677: 0.2%

over 10.0 seconds = 62330: 0.5%

non valid response = 0: 0%


  • From the courses.webwork.maa.org timing log:


Data from September 8, 2011 - January 10, 2012

count = 7064845

under 0.1 seconds = 3947249: 55.9%

between 0.1 and 0.2 seconds = 1555935: 22%

between 0.2 and 0.5 seconds = 1284348: 18.2%

between 0.5 and 1.0 seconds = 186063: 2.6%

between 1.0 and 2.0 seconds = 56870: 0.8%

between 2.0 and 3.0 seconds = 14858: 0.2%

between 3.0 and 4.0 seconds = 6172: 0.1%

between 4.0 and 5.0 seconds = 3051: 0%

between 5.0 and 10.0 seconds = 5597: 0.1%

over 10.0 seconds = 4702: 0.1%

non valid response = 0: 0%


Note: The daily backup routine was moved from 12 midnight (a busy time for students to do work) to 6:00am. This seems to have resulted if fewer operations taking a long time.


  • From the Rochester server math.webwork.rochester.edu timing log:

Data from September 6, 2011 count = 2586

under 0.1 seconds = 1795: 69.4%

between 0.1 and 0.2 seconds = 223: 8.6%

between 0.2 and 0.5 seconds = 515: 19.9%

between 0.5 and 1.0 seconds = 36: 1.4%

between 1.0 and 2.0 seconds = 8: 0.3%

between 2.0 and 3.0 seconds = 8: 0.3%

between 3.0 and 4.0 seconds = 1: 0%

between 4.0 and 5.0 seconds = 0: 0%

between 5.0 and 10.0 seconds = 0: 0%

over 10.0 seconds = 0: 0%

non valid response = 0: 0%

webwork.math.missouri.edu

May 5, 2010 to Oct 10, 2011

Server: 32 bit freebsd 7.2 vm with 4GB of ram serving 1200-1400 students per semester

count = 4664624

under 0.1 seconds = 3696211: 79.2%

between 0.1 and 0.2 seconds = 536336: 11.5%

between 0.2 and 0.5 seconds = 363331: 7.8%

between 0.5 and 1.0 seconds = 48816: 1%

between 1.0 and 2.0 seconds = 13498: 0.3%

between 2.0 and 3.0 seconds = 2865: 0.1%

between 3.0 and 4.0 seconds = 1406: 0%

between 4.0 and 5.0 seconds = 666: 0%

between 5.0 and 10.0 seconds = 1036: 0%

over 10.0 seconds = 459: 0%

non valid response = 0: 0%


Note: This server had two "out of memory" events which brought the server down due to a freebsd kernel setting that we should have tweaked but didn't until we understood the problem. This isn't a concern with the linux kernel or 64bit freebsd machines. These two events probably account for most of the requests on the high end.

February 02, 2012 - May 22, 2012

Server: FreeBSD 8.2-RELEASE-p2 amd64, Intel Xeon Processor E5520, 16GB RAM

About 2300 users in SP12 Semester

count = 1438276

under 0.1 seconds = 1007856: 70.1%

between 0.1 and 0.2 seconds = 217355: 15.1%

between 0.2 and 0.5 seconds = 179686: 12.5%

between 0.5 and 1.0 seconds = 25465: 1.8%

between 1.0 and 2.0 seconds = 5565: 0.4%

between 2.0 and 3.0 seconds = 902: 0.1%

between 3.0 and 4.0 seconds = 375: 0%

between 4.0 and 5.0 seconds = 227: 0%

between 5.0 and 10.0 seconds = 345: 0%

over 10.0 seconds = 500: 0%

non valid response = 0: 0%

Results from load monitoring software

Webmin on courses.maa.org

load on courses.webwork.maa.org 30 Aug 11 - 6 Sept 11

External Links