Onderwerp: Vreemde laadproblemen met 1 website.

stracestrace -f -o trace.txt /etc/rc.d/init.d/httpd start&

Dan kan je bijvb zien welke files allemaal geopent worden en als je live meekijkt kan je zien waar hij op vastloopt (waarschijnlijk). Wat is de load van de server als je de site opent en heeft de programmeur niet toevallig sleep ingebouwd ^^. In ieder geval, succes hiermee!

@T.Bloo:
Dit zit ik in de server status als ik deze refresh meteen nadat ik de pagina probeer te openen en die vertraging speelt:

Code:

9-1 22283 0/67/2279 W  2.18 14 0 0.0 0.39 30.62  82.169.xx.xx  www.satellietservice.be GET / HTTP/1.1

En nog eentje een stukje lager

Code:

20-1 - 0/0/152 .  0.70 2743 63 0.0 0.00 1.12  127.0.0.1 www.satellietservice.be GET /empcentauri-21-p-36.html HTTP/1.1

Meer regels zijn er niet.

waar zou die localhost request vandaan komen?

@yourwebhoster: Heb het eens geprobeerd maar daar kom ik geen wijs uit. Live zie ik niets gebeuren en als ik in de trace.txt op de domeinnaam ga zoeken krijg ik zoiets:

22996 stat64("/home/satservice/domains/satellietservice.be/public_html", {st_mode=S_IFDIR|0711, st_size=4096, ...}) = 0
22996 open("/etc/passwd", O_RDONLY) = 5
22996 fcntl64(5, F_GETFD) = 0
22996 fcntl64(5, F_SETFD, FD_CLOEXEC) = 0
22996 fstat64(5, {st_mode=S_IFREG|0644, st_size=3932, ...}) = 0
22996 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7f82000
22996 read(5, "root:x:0:0:root:/root:/bin/bash\n"..., 4096) = 3932
22996 close(5) = 0
22996 munmap(0xb7f82000, 4096) = 0
22996 open("/etc/group", O_RDONLY) = 5
22996 fcntl64(5, F_GETFD) = 0
22996 fcntl64(5, F_SETFD, FD_CLOEXEC) = 0
22996 fstat64(5, {st_mode=S_IFREG|0644, st_size=1460, ...}) = 0

Er draait wel nog een oudere mod_ruid versie, zou dat invloed kunnen hebben?
Blijft wel vreemd dat het euvel pas eergisteren opeens begon volgens betreffende.

Is de OScommerce installatie wel up to date?

Het is inderdaad zwaar raar. Ik verdenk dat eerste stukje javascript, misschien dat even weghalen?

@Ahmed: Ja die is pas geupdate, maar is wel een customized versie door extern bedrijf.

@T. Bloo: Da's misschien een optie om eens te proberen of dat helpt, ik zal het doorgeven.

Kwestie is doorgegeven aan het bedrijf wat de shop onderhoudt.

Wat ik vreemd blijf vinden is dat het exact maar dan ook exact 1 minuut vertraging is, op de seconde af. Dat lijkt dus sterk op een timeout.
Misschien zit er wel een timeout van een minuut in een java scriptje ofzo, ik heb geen idee.

Of ze zijn een sleep() vergeten in de code o.i.d.

Zou goed kunnen, als het maar niet aan de server ligt ben ik al happy.

In Direct Admin heb je hele uitgebreide logs die je precies vertellen wat er op je server gebeurt. Ik zou zeggen hier even in spitten.

Ehmz... die leest voor zover ik weet gewoon de logfiles in /var/log/httpd* uit en evt. /home/user/domains/logs en daar ben ik al via ssh in aan het rondsnuffelen geweest.

Als je 'php index.php' uitvoert, heb je dan ook deze vertraging,
Zoja, run je even 'strace php index.php'.

Veel kans dat je snel achter de oorzaak komt zo

laat maar...

@Dupondje, ja dan heb ik ook die vertraging, ik heb eens gedaan wat jij zei en daar viel me het volgende op:

socket(PF_FILE, SOCK_STREAM, 0) = 4
fcntl64(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0
connect(4, {sa_family=AF_FILE, path="/var/run/nscd/socket"...}, 110) = -1 ENOENT (No such file or directory)
close(4)

Vreemd want die /var/run/nscd/ bestaat wel, inderdaad geen socket te vinden.

En een stukje later blijft hij hier lang op hangen:

munmap(0xb7561000, 4096) = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 4
connect(4, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("78.46.89.18")}, 28) = 0
fcntl64(4, F_GETFL) = 0x2 (flags O_RDWR)
fcntl64(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0
gettimeofday({1297726036, 600072}, NULL) = 0
poll([{fd=4, events=POLLOUT}], 1, 0) = 1 ([{fd=4, revents=POLLOUT}])
send(4, "u\246\1\0\0\1\0\0\0\0\0\0\nininininin\2in\0\0\1\0 \1", 31, MSG_NOSIGNAL) = 31
poll([{fd=4, events=POLLIN}], 1, 5000) = 1 ([{fd=4, revents=POLLIN}])
ioctl(4, FIONREAD, [372]) = 0
recvfrom(4, "u\246\201\200\0\1\0\1\0\4\0\f\nininininin\2in\0\0 \1\0\1\300"..., 1024, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("78.46.89.18")}, [16]) = 372
close(4) = 0
gettimeofday({1297726036, 600617}, NULL) = 0
socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 4
fcntl64(4, F_GETFL) = 0x2 (flags O_RDWR)
fcntl64(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0
connect(4, {sa_family=AF_INET, sin_port=htons(80), sin_addr=inet_addr("91.204.48.45")}, 16) = -1 EINPROGRESS (Operation now in progress)
poll([{fd=4, events=POLLIN|POLLOUT|POLLERR|POLLHUP}], 1, 60000

Hier hangt ie dus.
Als ik dat nu even laat gaan dan komt opeens de rest er achteraan na een tijdje, en dan zie ik ook diverse timeouts er tussen zoals deze:

</body>
</html>
) = 43999
time(NULL) = 1297726422
poll([{fd=3, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
write(3, "Z\0\0\0\3select count(*) as total fr"..., 94) = 94
read(3, "\1\0\0\1\1\33\0\0\2\3def\0\0\0\5total\0\f?\0\25\0 \0\0\10\201"..., 16384) = 60
poll([{fd=3, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
write(3, "\371\1\0\0\3insert into sessions values"..., 509) = 509
read(3, "\7\0\0\1\0\1\0\2\0\0\0", 16384) = 11
close(2) = 0
close(1) = 0
munmap(0xb7f77000, 4096) = 0
close(0) = 0
munmap(0xb7f78000, 4096) = 0
rt_sigaction(SIGPIPE, {0x1, [PIPE], SA_RESTART}, {0x1, [PIPE], SA_RESTART}, 8) = 0
poll([{fd=3, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
write(3, "\1\0\0\0\1", 5) = 5
shutdown(3, 2 /* send and receive */) = 0
close(3) = 0
rt_sigaction(SIGPIPE, {0x1, [PIPE], SA_RESTART}, {0x1, [PIPE], SA_RESTART}, 8) = 0
brk(0x8e76000) = 0x8e76000
brk(0x8e12000) = 0x8e12000
munmap(0xb74f1000, 528384) = 0
munmap(0xb7572000, 782488) = 0
exit_group(0) = ?

Enig idee?