MySQL http 500 Fehler

[nish]

Hey Leute,
ich habe eine Anwendungssystem gebastelt, die ganz einfach Bücher verwalten soll. Das ganze wurde mit Hypernate (Java) und Mysql als Daten implementiert. Nun habe ich mir XAMPP heruntergeladen und das Projekt eingebunden und hat alles reibungslos geklappt. Danach habe ich die statische IP vergeben und ich konnte auch von anderen Rechner darauf zugreifen.
Am nächsten Tag habe ich gemerkt das mir die Startseite ohne Probleme angezeigt wurde aber sobald ich meine Benutzerdaten und Passwort eingegeben habe kam direkt ein HTTP 500 Fehler auf.
Ich habe den Rechner lediglich neustarten müssen und das Problem war behoben und am nächstes Tag ging das wieder los.
Ich habe keine Ahnung an was das liegt, und jeden Tag den Rechner neuzustarten ist keine Lösung, ich hoffe ihr könnt mir da helfen.

Danke!

[Altrea]

Hallo nish,

Da wir auch keine Hellseher sind oder aus dem Kaffeesatz lesen können, können wir dir nur nahe legen, die einzelnen Log-Dateien anzusehen.
Da du was von Java erwähnst nehme ich an, dass du Apache Tomcat verwendest. Dessen Logdateien findest du hier:
\xampp\tomcat\logs\

weitere vielleicht relevante Logdateien sind
Apache HTTPD: \xampp\apache\logs\error.log
MySQL: \xampp\mysql\data\mysql_error.log
PHP: \xampp\php\logs\php_error_log

mit freundlichen Grüßen,
Altrea

[nish]

Hallo Altrea,
ich habe mir die log Dateien angeschaut, die sehen folgendermaßen aus(das ist die log Datei in Tomcat, unter Error log hatte ich nichts bei mir hieß es localhost_access und dann das jeweilige Datum):

10.67.192.207 - - [30/Jan/2012:15:49:54 +0100] "GET /Biblio2/ HTTP/1.1" 200 2470
10.67.192.207 - - [30/Jan/2012:15:49:54 +0100] "GET /Biblio2/resources/css/global.css HTTP/1.1" 200 1704
10.67.192.207 - - [30/Jan/2012:15:49:54 +0100] "GET /Biblio2/resources/js/jquery.jeditable.mini.js HTTP/1.1" 200 8067
10.67.192.207 - - [30/Jan/2012:15:49:54 +0100] "GET /Biblio2/resources/js/jquery-1.6.4.min.js HTTP/1.1" 200 91669
10.67.192.207 - - [30/Jan/2012:15:49:54 +0100] "GET /Biblio2/resources/images/Logo.jpg HTTP/1.1" 200 5942
10.67.192.207 - - [30/Jan/2012:15:49:54 +0100] "GET /Biblio2/resources/images/background.jpg HTTP/1.1" 200 898563
10.67.192.207 - - [30/Jan/2012:15:49:54 +0100] "GET /favicon.ico HTTP/1.1" 200 21630
10.67.192.207 - - [30/Jan/2012:15:49:59 +0100] "POST /Biblio2/ HTTP/1.1" 500 15947

und die letzte Zeile (10.67.192.207 - - [30/Jan/2012:15:49:59 +0100] "POST /Biblio2/ HTTP/1.1" 500 15947) finde ich bei jeder log Datei.
Was hat das zu bedeuten?
Danke für deine Hilfe

[nish]

Mysql Error log sieht folgendermaßen aus:


120127 14:05:45 [Note] c:\xampp\mysql\bin\mysqld.exe: Normal shutdown

120127 14:05:45 [Note] Event Scheduler: Purging the queue. 0 events
120127 14:05:45 InnoDB: Starting shutdown...
120127 14:05:45 InnoDB: Shutdown completed; log sequence number 1818380
120127 14:05:45 [Note] c:\xampp\mysql\bin\mysqld.exe: Shutdown complete

120127 14:05:46 [Note] Plugin 'FEDERATED' is disabled.
120127 14:05:46 InnoDB: The InnoDB memory heap is disabled
120127 14:05:46 InnoDB: Mutexes and rw_locks use Windows interlocked functions
120127 14:05:46 InnoDB: Compressed tables use zlib 1.2.3
120127 14:05:46 InnoDB: Initializing buffer pool, size = 16.0M
120127 14:05:46 InnoDB: Completed initialization of buffer pool
120127 14:05:46 InnoDB: highest supported file format is Barracuda.
120127 14:05:46 InnoDB: Waiting for the background threads to start
120127 14:05:48 InnoDB: 1.1.8 started; log sequence number 1818380
120127 14:05:48 [Note] Event Scheduler: Loaded 0 events
120127 14:05:48 [Note] c:\xampp\mysql\bin\mysqld.exe: ready for connections.
Version: '5.5.16' socket: '' port: 3306 MySQL Community Server (GPL)
120127 14:07:09 [Note] c:\xampp\mysql\bin\mysqld.exe: Normal shutdown

120127 14:07:09 [Note] Event Scheduler: Purging the queue. 0 events
120127 14:07:09 InnoDB: Starting shutdown...
120127 14:07:09 InnoDB: Shutdown completed; log sequence number 1818380
120127 14:07:09 [Note] c:\xampp\mysql\bin\mysqld.exe: Shutdown complete

120127 14:08:07 [Note] Plugin 'FEDERATED' is disabled.
120127 14:08:07 InnoDB: The InnoDB memory heap is disabled
120127 14:08:07 InnoDB: Mutexes and rw_locks use Windows interlocked functions
120127 14:08:07 InnoDB: Compressed tables use zlib 1.2.3
120127 14:08:07 InnoDB: Initializing buffer pool, size = 16.0M
120127 14:08:07 InnoDB: Completed initialization of buffer pool
120127 14:08:07 InnoDB: highest supported file format is Barracuda.
120127 14:08:07 InnoDB: Waiting for the background threads to start
120127 14:08:08 InnoDB: 1.1.8 started; log sequence number 1818380
120127 14:08:08 [Note] Event Scheduler: Loaded 0 events
120127 14:08:08 [Note] c:\xampp\mysql\bin\mysqld.exe: ready for connections.
Version: '5.5.16' socket: '' port: 3306 MySQL Community Server (GPL)
120127 14:09:36 [Note] c:\xampp\mysql\bin\mysqld.exe: Normal shutdown

120127 14:09:36 [Note] Event Scheduler: Purging the queue. 0 events
120127 14:09:36 InnoDB: Starting shutdown...
120127 14:09:37 InnoDB: Shutdown completed; log sequence number 1818390
120127 14:09:37 [Note] c:\xampp\mysql\bin\mysqld.exe: Shutdown complete

120127 14:10:37 [Note] Plugin 'FEDERATED' is disabled.
120127 14:10:37 InnoDB: The InnoDB memory heap is disabled
120127 14:10:37 InnoDB: Mutexes and rw_locks use Windows interlocked functions
120127 14:10:37 InnoDB: Compressed tables use zlib 1.2.3
120127 14:10:37 InnoDB: Initializing buffer pool, size = 16.0M
120127 14:10:37 InnoDB: Completed initialization of buffer pool
120127 14:10:37 InnoDB: highest supported file format is Barracuda.
120127 14:10:37 InnoDB: Waiting for the background threads to start
120127 14:10:38 InnoDB: 1.1.8 started; log sequence number 1818390
120127 14:10:38 [Note] Event Scheduler: Loaded 0 events
120127 14:10:38 [Note] c:\xampp\mysql\bin\mysqld.exe: ready for connections.
Version: '5.5.16' socket: '' port: 3306 MySQL Community Server (GPL)

[Altrea]

Hallo nish,

Was hat das zu bedeuten?

Im Grunde bringt uns diese Logdatei nicht viele neuen Erkenntnisse. Die letzte Zeile sagt aus, dass auf das Anfordern einer Seite über die Modus POST ein HTTP Status 500 zurückgesendet wird.
Angefragt wird der Ordner /Biblio2/ ohne spezifische Datei, das heißt, dass die erste definierte Index Datei die gefunden wird ausgeliefert wird (z.B. die index.php).
Was die Fehlerursache eingrenzen kann ist, dass eine Anfrage über den Modus GET derselben Datei diesen Fehler nicht produziert.

Aber um was für einen Fehler es sich genau handelt, muss irgendwo in einer anderen Log-Datei zu finden sein.

dein MySQL Error log sieht unauffällig aus, nach dem ersten Überfliegen.

mit freundlichen Grüßen,
Altrea

[nish]

[nish]

Das ist die Fehlermeldung die ich erhalte...

[nish]

Feb 01, 2012 7:50:50 AM org.apache.catalina.core.ApplicationDispatcher invoke
SEVERE: Servlet.service() for servlet jsp threw exception
java.net.SocketException: Software caused connection abort: recv failed
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(Unknown Source)
at java.net.SocketInputStream.read(Unknown Source)
at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:114)
at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:161)
at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:189)
at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2537)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:2990)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:2979)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3520)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1990)
at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2151)
at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2625)
at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2119)
at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2281)
at org.hibernate.jdbc.AbstractBatcher.getResultSet(AbstractBatcher.java:208)
at org.hibernate.loader.Loader.getResultSet(Loader.java:1953)
at org.hibernate.loader.Loader.doQuery(Loader.java:802)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:274)
at org.hibernate.loader.Loader.doList(Loader.java:2533)
at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2276)
at org.hibernate.loader.Loader.list(Loader.java:2271)
at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:452)
at org.hibernate.hql.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:363)
at org.hibernate.engine.query.HQLQueryPlan.performList(HQLQueryPlan.java:196)
at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1268)
at org.hibernate.impl.QueryImpl.list(QueryImpl.java:102)
at de.nish.is2000.model.abstracts.AbstractEntity.find(AbstractEntity.java:202)
at de.nish.is2000.model.User.find(User.java:121)
at de.nish.is2000.view.helper.NavigationHelper.login(NavigationHelper.java:109)
at org.apache.jsp.pages.controll.login_controll_jsp._jspService(login_controll_jsp.java:93)
at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:722)
at org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:432)
at org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:390)
at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:334)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:722)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:305)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210)
at org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:684)
at org.apache.catalina.core.ApplicationDispatcher.doInclude(ApplicationDispatcher.java:593)
at org.apache.catalina.core.ApplicationDispatcher.include(ApplicationDispatcher.java:530)
at org.apache.jasper.runtime.JspRuntimeLibrary.include(JspRuntimeLibrary.java:954)
at org.apache.jsp.index_jsp._jspService(index_jsp.java:100)
at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:722)
at org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:432)
at org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:390)
at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:334)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:722)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:305)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:224)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:169)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:472)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:168)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:928)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:118)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:407)
at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:987)
at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:539)
at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:300)
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)

[nish]

Hallo Altrea,
ich glaube ich habe den Fehler rausgefunden, es liegt am Connection Pool, weißt du wie ich das bei tomcat und Mysql einstellen kann?

[Altrea]

Hallo nish,

Dabei kann ich dir leider nicht behilflich sein, tut mir leid.
Ich arbeite nicht mit Tomcat. Und einen Connection Pool habe ich mit MySQL nie verwendet. Diese Anforderung ergab sich für mich bisher nicht.

mit freundlichen Grüßen,
Altrea