Opened 6 years ago

Closed 5 years ago

#938 closed defect (worksforme)

i2p.client.I2PSessionMuxedImpl: Message NOT removed! then crit logs and i2psnark begins failing

Reported by: guest Owned by:
Priority: minor Milestone: 0.9.7
Component: streaming Version: 0.9.5
Keywords: Cc: zab@…
Parent Tickets:

Description

I2P version: 0.9.5-0
Java version: Oracle Corporation 1.7.0_17 (OpenJDK Runtime Environment 1.7.0_17-b02)
Wrapper version: none
Server version: 6.1.26
Servlet version: Jasper JSP 2.1 Engine
Platform: OpenBSD i386
Jbigi: Locally optimized native BigInteger? library loaded from file

The following issue just started effecting me. This is new and out of the ordinary. Reporting here just in case.

When torrenting with i2psnark:

Critlogs:

5/26/13 7:33:36 PM CRIT [cheduler 4/4] net.i2p.util.SimpleScheduler? : SimpleScheduler?: Scheduled task event on [Connection 1XnmNg==/Sub2XA== from YNJY up 3h wsize: 15 cwin: 15 rtt: 2577 rto: 5129 unacked out: 10 unacked in: 0 sent: 5711 rcvd: 1123 ackThru 5700 maxWin 128 MTU 1730] exited unexpectedly, please report

java.lang.IllegalMonitorStateException?
at java.lang.Object.notifyAll(Native Method)
at net.i2p.client.streaming.MessageOutputStream?.flushAvailable(MessageOutputStream?.java:483)
at net.i2p.client.streaming.Connection.sendAvailable(Connection.java:316)
at net.i2p.client.streaming.SchedulerConnectedBulk?.eventOccurred(SchedulerConnectedBulk?.java:59)
at net.i2p.client.streaming.Connection.eventOccurred(Connection.java:532)
at net.i2p.client.streaming.Connection$ConEvent?.timeReached(Connection.java:1109)
at net.i2p.util.SimpleScheduler?$RunnableEvent?.run(SimpleScheduler?.java:170)
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)

Router Logs:

5/26/13 8:09:37 PM ERROR [cheduler 2/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233236: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233236
Payload: [Payload]]

5/26/13 8:09:37 PM ERROR [cheduler 2/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233240: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233240
Payload: [Payload]]

5/26/13 8:09:37 PM ERROR [cheduler 2/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233238: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233238
Payload: [Payload]]

5/26/13 8:05:37 PM ERROR [cheduler 3/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233234: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233234
Payload: [Payload]]

5/26/13 8:04:37 PM ERROR [cheduler 3/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233233: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233233
Payload: [Payload]]

5/26/13 8:04:37 PM ERROR [cheduler 3/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233232: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233232
Payload: [Payload]]

5/26/13 7:58:36 PM ERROR [cheduler 2/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233231: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233231
Payload: [Payload]]

5/26/13 7:58:36 PM ERROR [cheduler 2/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233230: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233230
Payload: [Payload]]

5/26/13 7:57:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233229: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233229
Payload: [Payload]]

5/26/13 7:53:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233228: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233228
Payload: [Payload]]

5/26/13 7:51:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233227: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233227
Payload: [Payload]]

5/26/13 7:51:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233226: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233226
Payload: [Payload]]

5/26/13 7:50:36 PM ERROR [cheduler 2/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233225: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233225
Payload: [Payload]]

5/26/13 7:47:36 PM ERROR [cheduler 3/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233222: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233222
Payload: [Payload]]

5/26/13 7:47:36 PM ERROR [cheduler 3/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233223: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233223
Payload: [Payload]]

5/26/13 7:47:36 PM ERROR [cheduler 3/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233224: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233224
Payload: [Payload]]

5/26/13 7:45:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233220: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233220
Payload: [Payload]]

5/26/13 7:45:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233221: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233221
Payload: [Payload]]

5/26/13 7:44:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233217: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233217
Payload: [Payload]]

5/26/13 7:44:36 PM ERROR [cheduler 1/4] i2p.client.I2PSessionMuxedImpl: Message NOT removed! id=60233218: [MessagePayloadMessage?:

SessionId?: 19390
MessageId?: 60233218
Payload: [Payload]]

Subtickets

Change History (5)

comment:1 Changed 6 years ago by zzz

  • Component changed from unspecified to streaming

No idea.

The crit log seems "impossible". Only thing I can think of is a hardware glitch or JVM bug.

The error logs aren't obviously related, as the crit log shouldn't have destroyed everything.

I suggest you restart I2P and see if it happens again.

comment:2 Changed 6 years ago by guest

This happened twice in the same 24 hour period (out of nowhere) while torrenting. The sequence of errors was the same both times. The only thing new or different for me was that I increased my bandwidth by 20k before the first errors happened. Thanks for your feedback.

comment:3 Changed 6 years ago by zzz

OK. You may wish to check if there's a new OpenJDK available.

comment:4 Changed 6 years ago by zab

  • Cc zab@… added

comment:5 Changed 5 years ago by zzz

  • Resolution set to worksforme
  • Status changed from new to closed

Looked again and it still doesn't appear "possible". Please reopen if it happens again after you update Java.

Note: See TracTickets for help on using tickets.