Seltsame Aufrufe bringen Datenbank zum stocken

Beitrag von **Sneff** » 17.08.2006, 19:59

Hallo,

ich hoffe ich habe den richtigen bereich erwischt

Folgendes Problem:
Ich betreibe ein großes Forum (größtes seiner Art im deutschsprachigen Raum) mit ca 10.000 Visits pro Tag...
Die Site läuft auf einem eigenen Server von Allinkl (ManagedServerXL) und auch die Performance ist ok.

Gestern hat es zwei mal die Fehlermeldung "User ... has already more than 'max_user_connections' active connections".

Heute schaue ich in die Logs hinein und finde an beiden Uhrzeiten Einträge der folgenden Art:

Code: Alles auswählen

217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;27 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;27 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;27 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;27 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;27 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;27 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
&#91;1 normaler Zugriff&#93;
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;28 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;28 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;28 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;28 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;28 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;28 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;28 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
&#91;1 normaler Zugriff&#93;
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;29 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;29 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;29 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
&#91;1 normaler Zugriff&#93;
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;29 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;29 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
&#91;1 normaler Zugriff&#93;
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;29 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
&#91;1 normaler Zugriff&#93;
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
&#91;1 normaler Zugriff&#93;
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;30 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;31 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;31 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;31 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;31 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;31 +0200&#93; "GET / HTTP/1.1" 200 1330 "-" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"
217.84.205.167 - - &#91;16/Aug/2006&#58;13&#58;17&#58;31 +0200&#93; "GET /images/mysqlerror.jpg HTTP/1.1" 304 - "http&#58;//www.example.com/" "Mozilla/4.0 &#40;compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322&#41;"

Die mysqlerror.jpg ist eine kleine Grafik welches bei der genannten Fehlermeldung ausgegeben wird. Danach hat es mit den Zugriffen aufgehört.
Im normalfall befinden sich 5-10 Zugriffe pro Sekunden von unterschiedlichen IPs in den Logs um diese Zeit. Hier kommen andere PCs nur ganz selten zum Zuge und dann ist die Site kurz down.

Was mich jetzt wundert: Der versuchte Aufruf der URL "-" (das steht so drin, habe ich nicht "zensiert"). Ist das ein normaler, regulärer Aufruf? ?(
Oder hat sich da evtl ein Script verhakt und Erzeugt eine Endlosschleife (denke da an htaccess zB bzw ein modrewrite darin?)

Der andere Ausfall ging genauso von statten, aber von einer ClassA-IP mit anderem UserAgent. Daher vermute ich dass es zwei Rechner waren die darauf unfreiwillig gestoßen sind. Wenn das so ist, muss das ein seltener Fehler sein, denn dieser Fehler ist auch recht selten (vllt 2-4 mal die Woche) - bei 70.000 Besuchern die Woche ist das recht wenig wie ich finde...

Habt ihr eine Idee was das sein könnte bzw wo ich ansetzen könnte mit der Fehlerbehebung?

Bin für jeden kleinen Tipp dankbar

thx
Sneff

PS: was sagt eigentlich die Zahl (zB 1330) hinter dem Status 200 aus? Ist das der Soruce-Port auf der Source-IP?

von **Anzeige von ABAKUS** »

Content Erstellung von ABAKUS Internet Marketing
Ihre Vorteile:

einzigartige Texte
suchmaschinenoptimierte Inhalte
eine sinnvolle Content-Strategie
Beratung und Umsetzung

Jetzt anfragen: 0511 / 300325-0

Beitrag von **Fox Mulder** » 17.08.2006, 20:44

Sneff hat geschrieben:PS: was sagt eigentlich die Zahl (zB 1330) hinter dem Status 200 aus? Ist das der Soruce-Port auf der Source-IP?

Das ist die Content Length in Bytes.
Sieht nach einer leeren Seite aus, eventuell nur eine 404 not found Fehlerseite die aber einen 200 found zurückgibt.

Mir sieht das nicht nach Absicht aus. Wenn dich jemand per DOS lahmlegen wollte würde er keine leere Seite ziehen.

Beitrag von **Sneff** » 17.08.2006, 20:54

Das Log zeigt ja als wäre es eine Endlosschleife, die erst durch die hängende Datenbank beendet wird.
Die 404-Seiten machen kein Problem, hab ich eben getestet...

von **Anzeige von ABAKUS** »

Beitrag von **oldInternetUser** » 17.08.2006, 21:05

- Wie sehen denn die Zeilen aus, wenn die Startseite von einer anderen IP aufgerufen wird? Sprich: Werden dann auch 1330 Byte ausgeliefert?

- Was passiert, wenn man die IP aufruft (http:// 192 . 168. 254. 254/)? Wird dann so etwas ausgeliefert?

- Die verursachende IP: Welche Seite hat die als allererstes bzw. noch als letztes in einem 'normalen Leserhythmus' aufgerufen? Ist es womöglich ein Fehler auf einer Unterseite, der von der .htaccess falsch verarbeitet wird und damit die Dauerschleife produziert?

Beitrag von **Fox Mulder** » 17.08.2006, 21:08

Sneff hat geschrieben:Das Log zeigt ja als wäre es eine Endlosschleife, die erst durch die hängende Datenbank beendet wird.

Schau Dir an ob das Errorhandling in der htaccses nicht das Problem ist. Ist alles korrekt ausgeführt ? Beispiele hier: https://www.at-web.de/tricky/fehlermeldungen.htm

Beitrag von **Sneff** » 17.08.2006, 21:13

Ah mist, die Angabe "GET / HTTP/1.1" heißt ja er hat die Hauptseite aufgerufen, das "-" zeigt nur dass er manuell aufgerufen hat und daher keinen Referrer hatte.

Die Logs zeigen dass der User regulär über Google kam, dann etwas in der Site gelesen hat und ca 2 Minuten nach dem ersten Auftauchen in den Logs kamen diese Mehrfachen aufrufe

Nur warum ruft sein Browser mehrfach die Startseite auf?
Bei allen beiden Fehlern ist kein Referrer in den Logs zu sehen bei dem Aufruf
Die htaccess werde ich mal überprüfen, aber bei der Besuchermenge meiner Site müsste es, wenn dort ein Fehler ist, öfter zu diesem Ausfall kommen...

Die Site kann man leider nicht per IP aufrufen (Forbidden)
Aber es sind nicht immer 1330 byte.
Bei einem anderen Vorfall waren es 12222