Opened 6 years ago

Closed 5 years ago

#997 closed defect (no response)

Crash

Reported by: nextloop Owned by:
Priority: minor Milestone: 0.9.8
Component: unspecified Version: 0.9.7
Keywords: Cc:
Parent Tickets:

Description (last modified by zzz)

Router is hung. Webinterface stays online, but no tunnel connections anymore. Job lag is very high (>1s).

It is on a raspberry pi. With oracle JVM 1.7

Here are some logs:

    03/08/13 06:25:00 CRIT [uterWatchdog] 2p.router.tasks.RouterWatchdog: Router appears hung, or there is severe network congestion. Watchdog starts barking!
    03/08/13 04:04:00 CRIT [uterWatchdog] et.i2p.router.tasks.ThreadDump: Threads dumped to wrapper log
    03/08/13 04:03:59 CRIT [uterWatchdog] 2p.router.tasks.RouterWatchdog: Router appears hung, or there is severe network congestion. Watchdog starts barking!
    03/08/13 03:47:25 CRIT [mer C~wA 2/4] net.i2p.util.SimpleTimer2 : Streaming Timer C~wA: Timed task net.i2p.client.streaming.Connection$ResendPacketEvent@f476b5 exited unexpectedly, please report
         java.lang.OutOfMemoryError: Java heap space
         at net.i2p.util.ByteCache.acquire(ByteCache.java:157)
         at net.i2p.client.streaming.PacketQueue.enqueue(PacketQueue.java:74)
         at net.i2p.client.streaming.Connection$ResendPacketEvent.retransmit(Connection.java:1270)
         at net.i2p.client.streaming.Connection$ResendPacketEvent.timeReached(Connection.java:1141)
         at net.i2p.util.SimpleTimer2$TimedEvent.run(SimpleTimer2.java:348)
         at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
         at java.util.concurrent.FutureTask.run(FutureTask.java:166)
         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:178)
         at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:292)
         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
         at java.lang.Thread.run(Thread.java:722)

Wrapper.log:

2013/08/03 04:04:00 | 	- parking to wait for  <0xab3eefc0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
2013/08/03 04:04:00 | 	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
2013/08/03 04:04:00 | 	at gnu.crypto.prng.AsyncFortunaStandalone.run(AsyncFortunaStandalone.java:134)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 	at net.i2p.util.I2PThread.run(I2PThread.java:85)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "BWRefiller" daemon prio=10 tid=0xa7c005d0 nid=0x30c2 sleeping[0xa8075000]
2013/08/03 04:04:00 |    java.lang.Thread.State: TIMED_WAITING (sleeping)
2013/08/03 04:04:00 | 	at java.lang.Thread.sleep(Native Method)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.FIFOBandwidthRefiller.run(FIFOBandwidthRefiller.java:110)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 	at net.i2p.util.I2PThread.run(I2PThread.java:85)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleTimer2 4/4" daemon prio=10 tid=0x00e64b78 nid=0x30bf waiting on condition [0xa7b0e000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6718> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1085)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleTimer2 3/4" daemon prio=10 tid=0x00e635c0 nid=0x30be waiting on condition [0xa7b5e000]
2013/08/03 04:04:00 |    java.lang.Thread.State: TIMED_WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6718> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleTimer2 2/4" daemon prio=10 tid=0x00f05d20 nid=0x30bd waiting on condition [0xa7bae000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6718> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1085)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleTimer2 1/4" daemon prio=10 tid=0x00e630d0 nid=0x30bc waiting on condition [0xa7bfe000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6718> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1085)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Job Queue Pumper" daemon prio=10 tid=0x00f10570 nid=0x30b7 in Object.wait() [0xa7d55000]
2013/08/03 04:04:00 |    java.lang.Thread.State: TIMED_WAITING (on object monitor)
2013/08/03 04:04:00 | 	at java.lang.Object.wait(Native Method)
2013/08/03 04:04:00 | 	at net.i2p.router.JobQueue$QueuePumper.run(JobQueue.java:534)
2013/08/03 04:04:00 | 	- locked <0xab1a6a08> (a java.lang.Object)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 	at net.i2p.util.I2PThread.run(I2PThread.java:85)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleScheduler 4/4" daemon prio=10 tid=0x00eb5388 nid=0x30b6 waiting on condition [0xa7da5000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6ab0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1085)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleScheduler 3/4" daemon prio=10 tid=0x00eb0910 nid=0x30b5 waiting on condition [0xa7df5000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6ab0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1085)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleScheduler 2/4" daemon prio=10 tid=0x00eaf190 nid=0x30b4 waiting on condition [0xa7e45000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6ab0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1085)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "SimpleScheduler 1/4" daemon prio=10 tid=0x00c9dd60 nid=0x30b3 waiting on condition [0xa7e95000]
2013/08/03 04:04:00 |    java.lang.Thread.State: TIMED_WAITING (parking)
2013/08/03 04:04:00 | 	at sun.misc.Unsafe.park(Native Method)
2013/08/03 04:04:00 | 	- parking to wait for  <0xab1a6ab0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
2013/08/03 04:04:00 | 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090)
2013/08/03 04:04:00 | 	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
2013/08/03 04:04:00 | 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "LogWriter" daemon prio=10 tid=0x00d536c0 nid=0x30b2 in Object.wait() [0xa7ee5000]
2013/08/03 04:04:00 |    java.lang.Thread.State: TIMED_WAITING (on object monitor)
2013/08/03 04:04:00 | 	at java.lang.Object.wait(Native Method)
2013/08/03 04:04:00 | 	at net.i2p.util.LogWriter.flushRecords(LogWriter.java:110)
2013/08/03 04:04:00 | 	- locked <0xab1a6dd0> (a net.i2p.util.LogWriter)
2013/08/03 04:04:00 | 	at net.i2p.util.LogWriter.flushRecords(LogWriter.java:68)
2013/08/03 04:04:00 | 	at net.i2p.util.LogWriter.run(LogWriter.java:56)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 	at net.i2p.util.I2PThread.run(I2PThread.java:85)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Timestamper" daemon prio=10 tid=0x00fca3c0 nid=0x30b1 waiting on condition [0xa7f35000]
2013/08/03 04:04:00 |    java.lang.Thread.State: TIMED_WAITING (sleeping)
2013/08/03 04:04:00 | 	at java.lang.Thread.sleep(Native Method)
2013/08/03 04:04:00 | 	at net.i2p.router.time.RouterTimestamper.run(RouterTimestamper.java:206)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 	at net.i2p.util.I2PThread.run(I2PThread.java:85)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "DestroyJavaVM" prio=10 tid=0x00c7bc88 nid=0x308f waiting on condition [0x00000000]
2013/08/03 04:04:00 |    java.lang.Thread.State: RUNNABLE
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Wrapper-Connection" daemon prio=10 tid=0x00fabad8 nid=0x30aa runnable [0xa8025000]
2013/08/03 04:04:00 |    java.lang.Thread.State: RUNNABLE
2013/08/03 04:04:00 | 	at java.net.SocketInputStream.socketRead0(Native Method)
2013/08/03 04:04:00 | 	at java.net.SocketInputStream.read(SocketInputStream.java:150)
2013/08/03 04:04:00 | 	at java.net.SocketInputStream.read(SocketInputStream.java:121)
2013/08/03 04:04:00 | 	at java.net.SocketInputStream.read(SocketInputStream.java:203)
2013/08/03 04:04:00 | 	at java.io.DataInputStream.readByte(DataInputStream.java:265)
2013/08/03 04:04:00 | 	at org.tanukisoftware.wrapper.WrapperManager.handleBackend(WrapperManager.java:5172)
2013/08/03 04:04:00 | 	at org.tanukisoftware.wrapper.WrapperManager.run(WrapperManager.java:5545)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Wrapper-Control-Event-Monitor" daemon prio=10 tid=0x00f9c178 nid=0x30a8 sleeping[0xa80c5000]
2013/08/03 04:04:00 |    java.lang.Thread.State: TIMED_WAITING (sleeping)
2013/08/03 04:04:00 | 	at java.lang.Thread.sleep(Native Method)
2013/08/03 04:04:00 | 	at org.tanukisoftware.wrapper.WrapperManager$3.run(WrapperManager.java:978)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Service Thread" daemon prio=10 tid=0x00d36f10 nid=0x3099 runnable [0x00000000]
2013/08/03 04:04:00 |    java.lang.Thread.State: RUNNABLE
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "C1 CompilerThread0" daemon prio=10 tid=0x00d358a8 nid=0x3098 waiting on condition [0x00000000]
2013/08/03 04:04:00 |    java.lang.Thread.State: RUNNABLE
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Signal Dispatcher" daemon prio=10 tid=0x00d34320 nid=0x3097 waiting on condition [0x00000000]
2013/08/03 04:04:00 |    java.lang.Thread.State: RUNNABLE
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Finalizer" daemon prio=10 tid=0x00cf8570 nid=0x3096 in Object.wait() [0xa84c1000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (on object monitor)
2013/08/03 04:04:00 | 	at java.lang.Object.wait(Native Method)
2013/08/03 04:04:00 | 	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
2013/08/03 04:04:00 | 	- locked <0xab125c10> (a java.lang.ref.ReferenceQueue$Lock)
2013/08/03 04:04:00 | 	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
2013/08/03 04:04:00 | 	at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "Reference Handler" daemon prio=10 tid=0x00cf3800 nid=0x3095 in Object.wait() [0xa8511000]
2013/08/03 04:04:00 |    java.lang.Thread.State: WAITING (on object monitor)
2013/08/03 04:04:00 | 	at java.lang.Object.wait(Native Method)
2013/08/03 04:04:00 | 	at java.lang.Object.wait(Object.java:503)
2013/08/03 04:04:00 | 	at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133)
2013/08/03 04:04:00 | 	- locked <0xab125930> (a java.lang.ref.Reference$Lock)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | "VM Thread" prio=10 tid=0x00cee178 nid=0x3094 runnable 
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | JNI global references: 399
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | Found one Java-level deadlock:
2013/08/03 04:04:00 | =============================
2013/08/03 04:04:00 | "NTCP reader 2/4":
2013/08/03 04:04:00 |   waiting to lock monitor 0x0150b110 (object 0xac1d97b8, a net.i2p.router.transport.ntcp.NTCPConnection),
2013/08/03 04:04:00 |   which is held by "NTCP Pumper"
2013/08/03 04:04:00 | "NTCP Pumper":
2013/08/03 04:04:00 |   waiting to lock monitor 0x01876190 (object 0xac1d98a0, a net.i2p.router.transport.ntcp.NTCPConnection),
2013/08/03 04:04:00 |   which is held by "NTCP reader 2/4"
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | Java stack information for the threads listed above:
2013/08/03 04:04:00 | ===================================================
2013/08/03 04:04:00 | "NTCP reader 2/4":
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPConnection.close(NTCPConnection.java:301)
2013/08/03 04:04:00 | 	- waiting to lock <0xac1d97b8> (a net.i2p.router.transport.ntcp.NTCPConnection)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPConnection.close(NTCPConnection.java:298)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPTransport.inboundEstablished(NTCPTransport.java:180)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPConnection.finishInboundEstablishment(NTCPConnection.java:244)
2013/08/03 04:04:00 | 	- locked <0xac1d98a0> (a net.i2p.router.transport.ntcp.NTCPConnection)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.EstablishState.verifyInbound(EstablishState.java:632)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.EstablishState.receiveInbound(EstablishState.java:306)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.EstablishState.receive(EstablishState.java:151)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.Reader.processRead(Reader.java:162)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.Reader.access$400(Reader.java:21)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.Reader$Runner.run(Reader.java:118)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 	at net.i2p.util.I2PThread.run(I2PThread.java:85)
2013/08/03 04:04:00 | "NTCP Pumper":
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPConnection.close(NTCPConnection.java:301)
2013/08/03 04:04:00 | 	- waiting to lock <0xac1d98a0> (a net.i2p.router.transport.ntcp.NTCPConnection)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPConnection.close(NTCPConnection.java:298)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPTransport.removeCon(NTCPTransport.java:398)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPConnection.close(NTCPConnection.java:307)
2013/08/03 04:04:00 | 	- locked <0xac1d97b8> (a net.i2p.router.transport.ntcp.NTCPConnection)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.NTCPConnection.close(NTCPConnection.java:298)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.EventPumper.processRead(EventPumper.java:565)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.EventPumper.processKeys(EventPumper.java:358)
2013/08/03 04:04:00 | 	at net.i2p.router.transport.ntcp.EventPumper.run(EventPumper.java:181)
2013/08/03 04:04:00 | 	at java.lang.Thread.run(Thread.java:722)
2013/08/03 04:04:00 | 	at net.i2p.util.I2PThread.run(I2PThread.java:85)
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | Found 1 deadlock.
2013/08/03 04:04:00 | 
2013/08/03 04:04:00 | Heap
2013/08/03 04:04:00 |  def new generation   total 39296K, used 12507K [0xa8640000, 0xab0e0000, 0xab0e0000)
2013/08/03 04:04:00 |   eden space 34944K,  27% used [0xa8640000, 0xa8fa4208, 0xaa860000)
2013/08/03 04:04:00 |   from space 4352K,  66% used [0xaa860000, 0xaab32a08, 0xaaca0000)
2013/08/03 04:04:00 |   to   space 4352K,   0% used [0xaaca0000, 0xaaca0000, 0xab0e0000)
2013/08/03 04:04:00 |  tenured generation   total 87424K, used 79222K [0xab0e0000, 0xb0640000, 0xb0640000)
2013/08/03 04:04:00 |    the space 87424K,  90% used [0xab0e0000, 0xafe3da60, 0xafe3dc00, 0xb0640000)
2013/08/03 04:04:00 |  compacting perm gen  total 22016K, used 21861K [0xb0640000, 0xb1bc0000, 0xb4640000)
2013/08/03 04:04:00 |    the space 22016K,  99% used [0xb0640000, 0xb1b99760, 0xb1b99800, 0xb1bc0000)
2013/08/03 04:04:00 | No shared spaces configured.
2013/08/03 04:04:00 | 
2013/08/03 04:04:18 | CRIT  [uterWatchdog] 2p.router.tasks.RouterWatchdog: Router appears hung, or there is severe network congestion.  Watchdog starts barking!
2013/08/03 04:04:18 | CRIT  [uterWatchdog] et.i2p.router.tasks.ThreadDump: Threads dumped to wrapper log
2013/08/03 06:25:28 | CRIT  [uterWatchdog] 2p.router.tasks.RouterWatchdog: Router appears hung, or there is severe network congestion.  Watchdog starts barking!

Subtickets

Change History (3)

comment:1 Changed 6 years ago by killyourtv

I highly recommend that you try ​JDK8 on the Pi.

I had all kinds of crazy errors and poor performance (such as the CPU pegged at 100% utilization) until I started using JDk8.

comment:2 Changed 6 years ago by zzz

  • Description modified (diff)

Yeah, just out of memory / overloaded. Increase memory, reduce max connections, try JDK8, etc.

comment:3 Changed 5 years ago by zzz

  • Resolution set to no response
  • Status changed from new to closed

Closing, presumed JVM / /overload / memory related.

Note: See TracTickets for help on using tickets.