Biblioteka 2.0 on Facebook
Biblioteka 2.0 Strona Główna

Biblioteka 2.0
Forum społeczności czytelników i bibliotekarzy cyfrowych

FAQFAQ  SzukajSzukaj  UżytkownicyUżytkownicy  GrupyGrupy  StatystykiStatystyki
RejestracjaRejestracja  ZalogujZaloguj  AlbumAlbum  DownloadDownload

Poprzedni temat «» Następny temat
dziwne logi - odsłona druga
Autor Wiadomość
RaKu 

Dołączył: 10 Gru 2009
Posty: 20
Skąd: WBP Opole
Poziom: 3
HP: 0/44
 0%
MP: 21/21
 100%
EXP: 2/9
 22%
Wysłany: 2011-04-09, 22:29   dziwne logi - odsłona druga

Witam ponownie.
Dziś rano przeczytałem nowe zapiski dLibry, ale tym razem wygląda to nieciekawie
Cytat:
INFO | jvm 1 | 2011/04/09 08:50:24 | 2011.04.09 08:50:24 [RMI TCP Connection(600)-192.168.0.10] JDBCTransaction
INFO | jvm 1 | 2011/04/09 08:50:24 | ERROR: JDBC commit failed
INFO | jvm 1 | 2011/04/09 08:50:24 | java.sql.SQLException: Communications link failure during commit(). Transaction resolution unknown.
INFO | jvm 1 | 2011/04/09 08:50:24 | at com.mysql.jdbc.Connection.commit(Connection.java:2169)
INFO | jvm 1 | 2011/04/09 08:50:24 | at com.mchange.v2.c3p0.impl.NewProxyConnection.commit(NewProxyConnection.java:703)
INFO | jvm 1 | 2011/04/09 08:50:24 | at org.hibernate.transaction.JDBCTransaction.commitAndResetAutoCommit(JDBCTransaction.java:139)
INFO | jvm 1 | 2011/04/09 08:50:24 | at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:115)
INFO | jvm 1 | 2011/04/09 08:50:24 | at pl.psnc.dlibra.db.HibernateController.commitTransaction(HibernateController.java:341)
INFO | jvm 1 | 2011/04/09 08:50:24 | at pl.psnc.dlibra.content.server.ContentServerImpl.writeCompressedEdition(ContentServerImpl.java:1196)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.lang.reflect.Method.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.server.UnicastServerRef.dispatch(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.Transport$1.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.security.AccessController.doPrivileged(Native Method)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.Transport.serviceCall(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport.handleMessages(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | 2011.04.09 08:50:24 [RMI TCP Connection(600)-192.168.0.10] HibernateController
INFO | jvm 1 | 2011/04/09 08:50:24 | ERROR: Database exception occured. Rollbacking transaction!
INFO | jvm 1 | 2011/04/09 08:50:24 | org.hibernate.TransactionException: JDBC commit failed
INFO | jvm 1 | 2011/04/09 08:50:24 | at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:130)
INFO | jvm 1 | 2011/04/09 08:50:24 | at pl.psnc.dlibra.db.HibernateController.commitTransaction(HibernateController.java:341)
INFO | jvm 1 | 2011/04/09 08:50:24 | at pl.psnc.dlibra.content.server.ContentServerImpl.writeCompressedEdition(ContentServerImpl.java:1196)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.lang.reflect.Method.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.server.UnicastServerRef.dispatch(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.Transport$1.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.security.AccessController.doPrivileged(Native Method)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.Transport.serviceCall(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport.handleMessages(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | Caused by: java.sql.SQLException: Communications link failure during commit(). Transaction resolution unknown.
INFO | jvm 1 | 2011/04/09 08:50:24 | at com.mysql.jdbc.Connection.commit(Connection.java:2169)
INFO | jvm 1 | 2011/04/09 08:50:24 | at com.mchange.v2.c3p0.impl.NewProxyConnection.commit(NewProxyConnection.java:703)
INFO | jvm 1 | 2011/04/09 08:50:24 | at org.hibernate.transaction.JDBCTransaction.commitAndResetAutoCommit(JDBCTransaction.java:139)
INFO | jvm 1 | 2011/04/09 08:50:24 | at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:115)
INFO | jvm 1 | 2011/04/09 08:50:24 | ... 16 more
INFO | jvm 1 | 2011/04/09 08:50:24 | 2011.04.09 08:50:24 [RMI TCP Connection(600)-192.168.0.10] HibernateController
INFO | jvm 1 | 2011/04/09 08:50:24 | ERROR: Database exception occured. Rollbacking transaction!
INFO | jvm 1 | 2011/04/09 08:50:24 | java.rmi.RemoteException: Database exception occured [JDBC commit failed]
INFO | jvm 1 | 2011/04/09 08:50:24 | at pl.psnc.dlibra.db.HibernateController.rollbackTransaction(HibernateController.java:471)
INFO | jvm 1 | 2011/04/09 08:50:24 | at pl.psnc.dlibra.db.HibernateController.commitTransaction(HibernateController.java:348)
INFO | jvm 1 | 2011/04/09 08:50:24 | at pl.psnc.dlibra.content.server.ContentServerImpl.writeCompressedEdition(ContentServerImpl.java:1196)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.lang.reflect.Method.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.server.UnicastServerRef.dispatch(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.Transport$1.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.security.AccessController.doPrivileged(Native Method)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.Transport.serviceCall(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport.handleMessages(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 08:50:24 | at java.lang.Thread.run(Unknown Source)

a później były czary...
Cytat:
INFO | jvm 1 | 2011/04/09 11:32:42 | 2011.04.09 11:32:41 [Timer-0] ThreadPoolAsynchronousRunner
INFO | jvm 1 | 2011/04/09 11:32:42 | WARN: Task com.mchange.v2.c3p0.stmt.GooGooStatementCache$1@123a458 (in deadlocked PoolThread) failed to complete in maximum time 60000ms. Trying interrupt().

... i tak przez pół godziny. Potem przez 7 godzin normalnie - no prawie ... bo już niebawem
Cytat:
INFO | jvm 1 | 2011/04/09 19:34:19 | 2011.04.09 19:34:19 [RMI TCP Connection(1784)-192.168.0.10] JDBCTransaction
INFO | jvm 1 | 2011/04/09 19:34:19 | ERROR: JDBC commit failed
INFO | jvm 1 | 2011/04/09 19:34:19 | java.sql.SQLException: Communications link failure during commit(). Transaction resolution unknown.
INFO | jvm 1 | 2011/04/09 19:34:19 | at com.mysql.jdbc.Connection.commit(Connection.java:2169)
INFO | jvm 1 | 2011/04/09 19:34:19 | at com.mchange.v2.c3p0.impl.NewProxyConnection.commit(NewProxyConnection.java:703)
INFO | jvm 1 | 2011/04/09 19:34:19 | at org.hibernate.transaction.JDBCTransaction.commitAndResetAutoCommit(JDBCTransaction.java:139)
INFO | jvm 1 | 2011/04/09 19:34:19 | at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:115)
INFO | jvm 1 | 2011/04/09 19:34:19 | at pl.psnc.dlibra.db.HibernateController.commitTransaction(HibernateController.java:341)
INFO | jvm 1 | 2011/04/09 19:34:19 | at pl.psnc.dlibra.content.server.ContentServerImpl.getContent(ContentServerImpl.java:2324)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.reflect.GeneratedMethodAccessor207.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.lang.reflect.Method.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.server.UnicastServerRef.dispatch(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.Transport$1.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.security.AccessController.doPrivileged(Native Method)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.Transport.serviceCall(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport.handleMessages(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | 2011.04.09 19:34:19 [RMI TCP Connection(1784)-192.168.0.10] HibernateController
INFO | jvm 1 | 2011/04/09 19:34:19 | ERROR: Database exception occured. Rollbacking transaction!
INFO | jvm 1 | 2011/04/09 19:34:19 | org.hibernate.TransactionException: JDBC commit failed
INFO | jvm 1 | 2011/04/09 19:34:19 | at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:130)
INFO | jvm 1 | 2011/04/09 19:34:19 | at pl.psnc.dlibra.db.HibernateController.commitTransaction(HibernateController.java:341)
INFO | jvm 1 | 2011/04/09 19:34:19 | at pl.psnc.dlibra.content.server.ContentServerImpl.getContent(ContentServerImpl.java:2324)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.reflect.GeneratedMethodAccessor207.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.lang.reflect.Method.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.server.UnicastServerRef.dispatch(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.Transport$1.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.security.AccessController.doPrivileged(Native Method)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.Transport.serviceCall(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport.handleMessages(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.lang.Thread.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | Caused by: java.sql.SQLException: Communications link failure during commit(). Transaction resolution unknown.
INFO | jvm 1 | 2011/04/09 19:34:19 | at com.mysql.jdbc.Connection.commit(Connection.java:2169)
INFO | jvm 1 | 2011/04/09 19:34:19 | at com.mchange.v2.c3p0.impl.NewProxyConnection.commit(NewProxyConnection.java:703)
INFO | jvm 1 | 2011/04/09 19:34:19 | at org.hibernate.transaction.JDBCTransaction.commitAndResetAutoCommit(JDBCTransaction.java:139)
INFO | jvm 1 | 2011/04/09 19:34:19 | at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:115)
INFO | jvm 1 | 2011/04/09 19:34:19 | ... 15 more
INFO | jvm 1 | 2011/04/09 19:34:19 | 2011.04.09 19:34:19 [RMI TCP Connection(1784)-192.168.0.10] HibernateController
INFO | jvm 1 | 2011/04/09 19:34:19 | ERROR: Database exception occured. Rollbacking transaction!
INFO | jvm 1 | 2011/04/09 19:34:19 | java.rmi.RemoteException: Database exception occured [JDBC commit failed]
INFO | jvm 1 | 2011/04/09 19:34:19 | at pl.psnc.dlibra.db.HibernateController.rollbackTransaction(HibernateController.java:471)
INFO | jvm 1 | 2011/04/09 19:34:19 | at pl.psnc.dlibra.db.HibernateController.commitTransaction(HibernateController.java:348)
INFO | jvm 1 | 2011/04/09 19:34:19 | at pl.psnc.dlibra.content.server.ContentServerImpl.getContent(ContentServerImpl.java:2324)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.reflect.GeneratedMethodAccessor207.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.lang.reflect.Method.invoke(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.server.UnicastServerRef.dispatch(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.Transport$1.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.security.AccessController.doPrivileged(Native Method)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.Transport.serviceCall(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport.handleMessages(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO | jvm 1 | 2011/04/09 19:34:19 | at java.lang.Thread.run(Unknown Source)

Pod wieczór zrestartowałem dLibre i chwilowo jest spokój

Czy ktoś rozumie cytowane hieroglify??
Za pomoc z góry dziękuję
 
     
mwerla 
Marcin Werla


Wiek: 41
Dołączył: 13 Lut 2007
Posty: 251
Skąd: Poznań, PCSS
Poziom: 14
HP: 0/426
 0%
MP: 203/203
 100%
EXP: 27/33
 81%
Wysłany: 2011-04-11, 08:11   

Kluczowe fragmenty:

Cytat:

INFO | jvm 1 | 2011/04/09 08:50:24 | 2011.04.09 08:50:24 [RMI TCP Connection(600)-192.168.0.10] JDBCTransaction
INFO | jvm 1 | 2011/04/09 08:50:24 | ERROR: JDBC commit failed
INFO | jvm 1 | 2011/04/09 08:50:24 | java.sql.SQLException: Communications link failure during commit(). Transaction resolution unknown.


Z tego co znalazłem w sieci, pewnie było tak, że dLibra przez sterownik JDBC próbowała zapisać dane do bazy (commit), ale nie doczekała się na odpowiedź od bazy czy commit się udał czy nie. Przyczyna braku odpowiedzi została określona jako błąd połączenia z bazą. Ze względu na to, że wynik transakcji bazodanowej nie był znany, została podjęta decyzja o wycofaniu jej.

Nie mieliśmy wcześniej takich zgłoszeń, a z tego co widzę w sieci to najczęściej pojawiają się sugestie, że przyczyną są problemy z siecią ale może też być coś nie tak z bazą.

Wydaje się, że restart nie powinien być konieczny, ale jeżeli to nie sieć, tylko baza to faktycznie restart dLibry najlepiej połączony z restartem bazy powinien pomóc.

Co do
Cytat:

INFO | jvm 1 | 2011/04/09 11:32:42 | 2011.04.09 11:32:41 [Timer-0] ThreadPoolAsynchronousRunner
INFO | jvm 1 | 2011/04/09 11:32:42 | WARN: Task com.mchange.v2.c3p0.stmt.GooGooStatementCache$1@123a458 (in deadlocked PoolThread) failed to complete in maximum time 60000ms. Trying interrupt().


To jakieś zapytanie się wykonywało ponad minutę więc została podjęta próba przerwania go. To znowu wskazuje że mogło coś się stać z bazą.

Nie jestem w stanie podać w tej chwili bardziej precyzyjnego rozwiązania, ale jeżeli sytuacja się powtórzy, proszę o kontakt z naszą pomocą techniczną.
_________________
Marcin Werla
Zespół Bibliotek Cyfrowych PCSS
 
 
     
Wyświetl posty z ostatnich:   
Ten temat jest zablokowany bez możliwości zmiany postów lub pisania odpowiedzi
Nie możesz pisać nowych tematów
Nie możesz odpowiadać w tematach
Nie możesz zmieniać swoich postów
Nie możesz usuwać swoich postów
Nie możesz głosować w ankietach
Nie możesz załączać plików na tym forum
Możesz ściągać załączniki na tym forum
Dodaj temat do Ulubionych
Wersja do druku

Skocz do:  

Powered by phpBB modified by Przemo © 2003 phpBB Group
Biblioteka 2.0 : Forum społeczności czytelników i bibliotekarzy cyfrowych [Dokument elektroniczny] - Tryb dostępu http://forum.biblioteka20.pl
Korzystanie z portalu oznacza akceptację naszej polityki prywatności.
Strona wygenerowana w 0.08 sekundy. Zapytań do SQL: 9