NCCOOS Trac Projects: Top | Web | Platforms | Processing | Viz | Sprints | Sandbox | (Wind)

Tracking down performance bottlenecks in PYDAP-powered ZPTs

Like this example

Issue: this page takes 5~7 seconds to load initially and then ~4 seconds thereafter

I added code to the python script called by the ZPT to print out some load times (via time.time() differencing). The last line of the ZPT as it now renders is this output. Elements are:

DAP openAP loopAT loopDT loopRH loopRD loopLoop totalScript total
1.13s (dap open) 0.54s (loop) 0.53s (loop) 0.53s (loop) 0.54s (loop) 0.52s (loop) 2.66s (loop total) 3.79s (total)
1.20s (dap open) 0.55s (loop) 0.55s (loop) 0.55s (loop) 0.54s (loop) 0.55s (loop) 2.75s (loop total) 3.95s (total)

Apache and Zope logs

152.2.175.228 - Anonymous [15/Sep/2008:13:55:46 -0400] "GET /Plone/platformPage HTTP/1.1" 200 16377 "" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/portal_javascripts/Plone Default/jquery-cachekey7049.js HTTP/1.1" 200 52068 "http://whewell.marine.unc.edu:9080/Plone/platformPage" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/portal_javascripts/Plone Default/jquery-cachekey7049.js HTTP/1.1" 200 52068 "" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/portal_javascripts/Plone Default/resourcebase2-dom-fp-cachekey9429.js HTTP/1.1" 200 39654 "http://whewell.marine.unc.edu:9080/Plone/platformPage" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/portal_javascripts/Plone Default/resourcebase2-dom-fp-cachekey9429.js HTTP/1.1" 200 39654 "" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/portal_css/Plone Default/base-cachekey9631.css HTTP/1.1" 200 45997 "http://whewell.marine.unc.edu:9080/Plone/platformPage" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/info_icon.gif HTTP/1.1" 200 1227 "http://whewell.marine.unc.edu:9080/Plone/platformPage" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/spinner.gif HTTP/1.1" 200 2337 "http://whewell.marine.unc.edu:9080/Plone/platformPage" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/favicon.ico HTTP/1.1" 200 2529 "" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/logo.jpg HTTP/1.1" 200 5457 "http://whewell.marine.unc.edu:9080/Plone/platformPage" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/input_background.gif HTTP/1.1" 200 759 "http://whewell.marine.unc.edu:9080/Plone/portal_css/Plone%20Default/base-cachekey9631.css" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/search_icon.gif HTTP/1.1" 200 732 "http://whewell.marine.unc.edu:9080/Plone/portal_css/Plone%20Default/base-cachekey9631.css" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/user.gif HTTP/1.1" 200 1223 "http://whewell.marine.unc.edu:9080/Plone/platformPage" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"
152.2.175.228 - Anonymous [15/Sep/2008:13:55:47 -0400] "GET /Plone/linkTransparent.gif HTTP/1.1" 200 402 "http://whewell.marine.unc.edu:9080/Plone/portal_css/Plone%20Default/base-cachekey9631.css" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.0.1) Gecko/2008070208 Firefox/3.0.1"


Apache
127.0.0.1 - - [15/Sep/2008:13:55:42 -0400] "GET /dods/nccoos/level1/jpier/met/jpier_met_2008_09.nc.dds HTTP/1.1" 200 2177 "-" "pydap/2.2.6.5"
127.0.0.1 - - [15/Sep/2008:13:55:43 -0400] "GET /dods/nccoos/level1/jpier/met/jpier_met_2008_09.nc.das HTTP/1.1" 200 4534 "-" "pydap/2.2.6.5"
127.0.0.1 - - [15/Sep/2008:13:55:44 -0400] "GET /dods/nccoos/level1/jpier/met/jpier_met_2008_09.nc.dods?air_pressure.air_pressure[2051:1:2051] HTTP/1.1" 200 138 "-" "pydap/2.2.6.5"
127.0.0.1 - - [15/Sep/2008:13:55:44 -0400] "GET /dods/nccoos/level1/jpier/met/jpier_met_2008_09.nc.dods?air_temp.air_temp[2051:1:2051] HTTP/1.1" 200 126 "-" "pydap/2.2.6.5"
127.0.0.1 - - [15/Sep/2008:13:55:45 -0400] "GET /dods/nccoos/level1/jpier/met/jpier_met_2008_09.nc.dods?dew_temp.dew_temp[2051:1:2051] HTTP/1.1" 200 126 "-" "pydap/2.2.6.5"
127.0.0.1 - - [15/Sep/2008:13:55:45 -0400] "GET /dods/nccoos/level1/jpier/met/jpier_met_2008_09.nc.dods?humidity.humidity[2051:1:2051] HTTP/1.1" 200 126 "-" "pydap/2.2.6.5"
127.0.0.1 - - [15/Sep/2008:13:55:46 -0400] "GET /dods/nccoos/level1/jpier/met/jpier_met_2008_09.nc.dods?rainfall_day.rainfall_day[2051:1:2051] HTTP/1.1" 200 138 "-" "pydap/2.2.6.5"

Attachments