Pénteken rendbetettük egy nagy terhelésű, nagyon lassú webalkalmazás adatbázisát. Azért csak az adatbázist, mert a kódhoz nem nyúlhattunk. Érzésre gyorsult tőle a webalkalmazás, de kíváncsi voltam, hogy pontosan mennyivel. Valami mérőszám kellett. A futó alkalmazáson trace-elni nem tudtunk. Ha egy lokális mentésen kattintgatok, annak semmi köze a valósághoz (például, mert nem tudom a felhasználók tipikus viselkedésmintáit szimulálni, vagy mert másik gépen fut). Végül Soci blogja alapján amellett döntöttünk, hogy az IIS logból találjuk ki, hogy mennyire gyorsultak a lekérdezések.

Az alapelv annyi, hogy a LogParser nevű eszközzel gyakorlatilag SQL lekérdezést futtatunk az IIS logon. Lekérdeztem a múlt szerdai (tehát optimalizálás előtti) és a most hétfői (tehát optimalizálás utáni) logból az adott portál *.aspx-eire beesett kérések átlagos kiszolgálási idejét:

A fenti screenshoton jól látszanak az egyszerű SQL lekérdezések, és hogy az oldalak válaszidejei 832 ms-ről, 722 ms-re estek vissza. Ám ebben még benne vannak a dokumentumletöltéseket kiszolgáló kamu DocDownload.aspx és PictureResolver.aspx oldalak (és ezek kiszolgálási ideje a letöltött dokumentum méretétől függ nem pedig az alkalmazás sebességétől). Az SQL-szerű nyelv miatt ezeket egyszerű kiszűrni:

Így az látszik, hogy az átlagos oldalkiszolgálási idő 1126 ms-ról 762-re esett vissza. Ez alapján a hétfői átlagos válaszidő a megelőző szerdainak csak 67 százaléka. Ez optimalizálás szempontjából még nem a világ vége, de legalább tudjuk hogy hol tartunk.

Az éppen (mármint most éppen) aktuális LogParser ingyen letölthető innen.