* Alex Wüst <alexander.wuest@orange-digital.de> [030116 15:33]:

Hallo Alex,

> Ich hab ein Verzeichnis mit 80 Artikeln darin gewählt. Im selben
> Moment in dem der Browser die Seite darstellt, erscheint der Eintrag
> im Logfile. Die Darstellung hat bei diesen 80 Artikeln (20 Artikel pro
> Seite) ca. 20 Sekunden gedauert.

wenn du wirklich wissen willst wo es bei euch hängt, dann häng doch mal
einen Profiler in das Skript. Dazu installierst du am besten
Devel::DProf (findest du im CPAN, ist wirklich einfach) und ersetzt in
dem fraglichen Skript die erste Zeile:

    #!/usr/local/bin/perl

durch
    
    #!/usr/local/bin/perl -d:DProf

Das Skript schreibt dann beim Aufruf eine Datei tmon.out (vermutlich ins
cgi-bin-Verzeichnis, hängt aber vom Webserver ab, beim Apachen ist das
aber so; also Rechte entsprechend vergeben!) in der die Profiling-Daten
stehen.

Das kann man sich dann mit (das Tool ist bei Devel::DProf dabei)

    dprofpp -u tmon.out

ansehen. Die Infos dort verlangen natürlich einen tiefgreifenden
Einblick in den Imperia-Code, aber Leute wie Guido können damit bestimmt
etwas anfangen.

Das ganze sieht dann etwa so aus (am Beispiel site_main.pl):

lackas@zel726$ dprofpp -u tmon.out  | less
Total Elapsed Time = 2.487648 Seconds
         User Time = 2.052011 Seconds
Exclusive Times
%Time ExclSec CumulS #Calls sec/call Csec/c  Name
 12.1   0.250  0.250      8   0.0312 0.0312  Locale::gettext_pp::__load_catalog
 4.92   0.101  0.131     42   0.0024 0.0031  base::import
 4.92   0.101  1.255     12   0.0084 0.1046  main::BEGIN
 3.80   0.078  0.124      9   0.0087 0.0137  Dispatcher::BEGIN
 3.41   0.070  0.123    154   0.0005 0.0008  Exporter::import
 3.41   0.070  0.089    256   0.0003 0.0003  Locale::Recode::new
 3.02   0.062  0.201    202   0.0003 0.0010  Locale::gettext_pp::LC_MESSAGES
 3.02   0.062  0.140      6   0.0104 0.0233  Imperia::I18N::GTXPartner::BEGIN
 2.68   0.055  0.054     81   0.0007 0.0007  Exporter::heavy_export
 2.29   0.047  0.092     12   0.0039 0.0077  Imperia::Menu::BEGIN
 2.29   0.047  0.050    606   0.0001 0.0001  Carp::ancestors
 2.29   0.047  0.054      5   0.0094 0.0108  Image::Link::BEGIN
 1.90   0.039  0.080    202   0.0002 0.0004  Carp::shortmess_heavy
 1.90   0.039  0.509      9   0.0043 0.0566  site_lowlevel::BEGIN
 1.51   0.031  0.024   1600   0.0000 0.0000  constant::__ANON__

Hier sieht man jetzt z.B., dass Imperia 12% der Gesamtzeit (hier etwa 2.5
Sekunden) mit dem Laden der Lokalisierungstabellen vertrödelt. Und man
erfährt wie oft eine Subroutine aufgerufen wurde, die lange das jeweils
gedauert hat, wieviel Zeit in Unter-Subroutinen verbracht wurde, etc...

Mit "-O xx" kann man angeben, dass man die obersten xx Subroutinen sehen
möchte (default ist 15). Weitere Details (z.B. zur Sortierung) entnehme
der man-page zu dprofpp: perldoc dprofpp

Poste das Ergebnis doch einfach mal hier (oder schick es per PM an z.B.
Guido). So sollte sich das Problem schnell einkreisen lassen.


Schöne Grüße nach München,
 Christian

p.s. Bitte kein TOFU. Danke.
-- 
Archiv der Mailingliste  : http://www.lackas.net/imp/ma/
Imperia-Resource-Page    : http://www.lackas.net/imp/
Wie zitiere ich richtig? : http://learn.to/quote/
Abmelden                 : mailto:majordomo@imperia.de?body=unsubscribe

© 2001, 2002 marchive.pl Christian Lackas

[HOME]   [MARCHIVE]   [INDEX]   [IMPERIA]   [IMPRESSUM]   [DELTA]