Opened 4 years ago

Closed 4 years ago

Last modified 4 years ago

#1520 closed defect (wontfix)

Message delay increase and random freezes after update to 0.9.18-20-rc from 0.9.18-18-rc

Reported by: djjeshk Owned by:
Priority: major Milestone: undecided
Component: unspecified Version: 0.9.19
Keywords: Cc:
Parent Tickets:

Description

I2P version: 0.9.18-20-rc
Java version: Oracle Corporation 1.8.0_31 (Java(TM) SE Runtime Environment 1.8.0_31-b13)
Wrapper version: 3.5.25
Server version: 8.1.16.v20140903
Servlet version: Jasper JSP 2.1 Engine
Platform: Windows XP x86 5.1
Processor: Core 2 (45nm) (core2)
Jbigi: Locally optimized native BigInteger? library loaded from file
Encoding: Cp1257
Charset: windows-1257

15.12.4 22:21:03 ↓↓↓ 1 similar message omitted ↓↓↓

15.12.4 22:19:19 ERROR [Queue Pumper] net.i2p.router.JobQueue? : Job ReadJob?: Job 19: DB Read Job out of order with job SendTimeoutJob?: Job 31479: Outbound client message send timeout difference of 103s

After i did upgrade to 0.9.18-20-rc router shows message delays measured in seconds and router CPU load periodically drops to near zero. As I looked at sources 0.9.19-0 is the same as 0.9.18-20-rc.
I did not saw delays like this in 0.9.18-18-rc

At downgrade crash encorouted:

wrapper  | Launching a JVM...
jvm 8    | WrapperManager: Initializing...
jvm 8    | Starting I2P 0.9.18-20-rc
jvm 8    | INFO: Update file exists [i2pupdate.zip] - installing
jvm 8    | INFO: 475 files extracted to C:\Program Files\i2p
jvm 8    | INFO: File [deletelist.txt] deleted
jvm 8    | INFO: Update installed
jvm 8    | INFO: Restarting after update
wrapper  | on_exit trigger matched.  Restarting the JVM.  (Exit code: 4)
wrapper  | Launching a JVM...
jvm 9    | WrapperManager: Initializing...
jvm 9    | Starting I2P 0.9.18-18-rc
jvm 9    | INFO: Native CPUID library jcpuid-x86-windows loaded from file
jvm 9    | INFO: Locally optimized native BigInteger library loaded from file
jvm 9    | INFO: Jetty 8.1.16.v20140903 logging to I2P logs using class org.ecli
pse.jetty.server.Server
jvm 9    | #
jvm 9    | # A fatal error has been detected by the Java Runtime Environment:
jvm 9    | #
jvm 9    | #  Internal Error (safepoint.cpp:716), pid=2296, tid=2408
jvm 9    | #  fatal error: Illegal threadstate encountered: 4
jvm 9    | #
jvm 9    | # JRE version: Java(TM) SE Runtime Environment (8.0_31-b13) (build 1.
8.0_31-b13)
jvm 9    | # Java VM: Java HotSpot(TM) Client VM (25.31-b07 mixed mode windows-x
86 )
jvm 9    | # Failed to write core dump. Minidumps are not enabled by default on
client versions of Windows
jvm 9    | #
jvm 9    | # An error report file with more information is saved as:
jvm 9    | # C:\Program Files\i2p\hs_err_pid2296.log
jvm 9    | #
jvm 9    | # If you would like to submit a bug report, please visit:
jvm 9    | #   http://bugreport.java.com/bugreport/crash.jsp
jvm 9    | # The crash happened outside the Java Virtual Machine in native code.

jvm 9    | # See problematic frame for where to report the bug.
jvm 9    | #
wrapper  | JVM exited unexpectedly.
wrapper  | Launching a JVM...
jvm 10   | WrapperManager: Initializing...
jvm 10   | Starting I2P 0.9.18-18-rc
jvm 10   | WARN: There may be another router already running. Waiting a while to
 be sure...

This was one time failure, after that router started correctly.
At 0.9.18-20-rc http console load hangs were detected, but in 0.9.18-18-rc it behaves normally. Message delays back to normal.
No console load hangs. Strange behaviour.

I2P version: 0.9.18-18-rc
Java version: Oracle Corporation 1.8.0_31 (Java(TM) SE Runtime Environment 1.8.0_31-b13)
Wrapper version: 3.5.25
Server version: 8.1.16.v20140903
Servlet version: Jasper JSP 2.1 Engine
Platform: Windows XP x86 5.1
Processor: Core 2 (45nm) (core2)
Jbigi: Locally optimized native BigInteger? library loaded from file
Encoding: Cp1257
Charset: windows-1257

Subtickets

Change History (7)

comment:1 Changed 4 years ago by djjeshk

hs_err_pid2296.log:

#
# A fatal error has been detected by the Java Runtime Environment:
#
#  Internal Error (safepoint.cpp:716), pid=2296, tid=2408
#  fatal error: Illegal threadstate encountered: 4
#
# JRE version: Java(TM) SE Runtime Environment (8.0_31-b13) (build 1.8.0_31-b13)
# Java VM: Java HotSpot(TM) Client VM (25.31-b07 mixed mode windows-x86 )
# Failed to write core dump. Minidumps are not enabled by default on client versions of Windows
#
# If you would like to submit a bug report, please visit:
#   http://bugreport.java.com/bugreport/crash.jsp
# The crash happened outside the Java Virtual Machine in native code.
# See problematic frame for where to report the bug.
#

---------------  T H R E A D  ---------------

Current thread (0x438ca400):  JavaThread "I2P Router Console" daemon [_thread_in_native, id=2408, stack(0x43c60000,0x43cb0000)]

Stack: [0x43c60000,0x43cb0000],  sp=0x43caee58,  free space=315k
Native frames: (J=compiled Java code, j=interpreted, Vv=VM code, C=native code)
V  [jvm.dll+0x197b8b]
V  [jvm.dll+0x1909e6]
V  [jvm.dll+0x156fb9]
V  [jvm.dll+0x16783a]
V  [jvm.dll+0x1831]
V  [jvm.dll+0xcae9d]
C  [systray4j.dll+0x2f4d]
C  [systray4j.dll+0x2de4]
C  [systray4j.dll+0x61f9]
j  snoozesoft.systray4j.NativeSysTray.addMainMenuNative(Lsnoozesoft/systray4j/SysTrayMenu;Ljava/lang/String;Ljava/lang/String;)I+0
j  snoozesoft.systray4j.NativeSysTray.addMainMenu(Lsnoozesoft/systray4j/SysTrayMenu;Ljava/lang/String;Ljava/lang/String;)V+24
j  snoozesoft.systray4j.SysTrayManager.addMainMenu(Lsnoozesoft/systray4j/SysTrayMenu;)V+49
j  snoozesoft.systray4j.SysTrayMenu.<init>(Lsnoozesoft/systray4j/SysTrayMenuIcon;Ljava/lang/String;Ljava/util/Vector;)V+77
j  snoozesoft.systray4j.SysTrayMenu.<init>(Lsnoozesoft/systray4j/SysTrayMenuIcon;Ljava/lang/String;)V+10
j  net.i2p.apps.systray.SysTray.<init>()V+58
j  net.i2p.apps.systray.SysTray.<clinit>()V+131
v  ~StubRoutines::call_stub
V  [jvm.dll+0x1473e5]
V  [jvm.dll+0x20d57e]
V  [jvm.dll+0x14747e]
V  [jvm.dll+0xb3dab]
V  [jvm.dll+0xb52e1]
V  [jvm.dll+0xb54b8]
V  [jvm.dll+0x7a4a6]
V  [jvm.dll+0x7b4d2]
V  [jvm.dll+0x76ae7]
j  net.i2p.router.web.RouterConsoleRunner.startTrayApp(Lnet/i2p/I2PAppContext;)V+71
j  net.i2p.router.web.RouterConsoleRunner.startup()V+15
j  net.i2p.router.startup.RouterAppManager.addAndStart(Lnet/i2p/app/ClientApp;[Ljava/lang/String;)Z+76
j  net.i2p.router.startup.LoadClientAppsJob$RunApp.run()V+103
j  java.lang.Thread.run()V+11
j  net.i2p.util.I2PThread.run()V+1
v  ~StubRoutines::call_stub
V  [jvm.dll+0x1473e5]
V  [jvm.dll+0x20d57e]
V  [jvm.dll+0x14747e]
V  [jvm.dll+0x147606]
V  [jvm.dll+0x147677]
V  [jvm.dll+0xf09df]
V  [jvm.dll+0x169acc]
V  [jvm.dll+0x16a35a]
V  [jvm.dll+0x1af0a6]
C  [msvcr100.dll+0x5c556]
C  [msvcr100.dll+0x5c600]
C  [kernel32.dll+0xb729]
C  0x00000000

Java frames: (J=compiled Java code, j=interpreted, Vv=VM code)
j  snoozesoft.systray4j.NativeSysTray.addMainMenuNative(Lsnoozesoft/systray4j/SysTrayMenu;Ljava/lang/String;Ljava/lang/String;)I+0
j  snoozesoft.systray4j.NativeSysTray.addMainMenu(Lsnoozesoft/systray4j/SysTrayMenu;Ljava/lang/String;Ljava/lang/String;)V+24
j  snoozesoft.systray4j.SysTrayManager.addMainMenu(Lsnoozesoft/systray4j/SysTrayMenu;)V+49
j  snoozesoft.systray4j.SysTrayMenu.<init>(Lsnoozesoft/systray4j/SysTrayMenuIcon;Ljava/lang/String;Ljava/util/Vector;)V+77
j  snoozesoft.systray4j.SysTrayMenu.<init>(Lsnoozesoft/systray4j/SysTrayMenuIcon;Ljava/lang/String;)V+10
j  net.i2p.apps.systray.SysTray.<init>()V+58
j  net.i2p.apps.systray.SysTray.<clinit>()V+131
v  ~StubRoutines::call_stub
j  net.i2p.router.web.RouterConsoleRunner.startTrayApp(Lnet/i2p/I2PAppContext;)V+71
j  net.i2p.router.web.RouterConsoleRunner.startup()V+15
j  net.i2p.router.startup.RouterAppManager.addAndStart(Lnet/i2p/app/ClientApp;[Ljava/lang/String;)Z+76
j  net.i2p.router.startup.LoadClientAppsJob$RunApp.run()V+103
j  java.lang.Thread.run()V+11
j  net.i2p.util.I2PThread.run()V+1
v  ~StubRoutines::call_stub

---------------  P R O C E S S  ---------------

Java Threads: ( => current thread )
  0x437f1c00 JavaThread "DH Precalc" [_thread_blocked, id=2892, stack(0x45520000,0x45570000)]
  0x434bc400 JavaThread "JobQueue 5/5" [_thread_blocked, id=2232, stack(0x454d0000,0x45520000)]
  0x434bb000 JavaThread "JobQueue 4/5" [_thread_blocked, id=5184, stack(0x45480000,0x454d0000)]
  0x434ba400 JavaThread "Thread-4" [_thread_in_native_trans, id=5068, stack(0x43d30000,0x43d80000)]
  0x437fe800 JavaThread "JobQueue 3/5" [_thread_blocked, id=3580, stack(0x453f0000,0x45440000)]
  0x437fe400 JavaThread "JobQueue 2/5" [_thread_in_vm, id=3020, stack(0x453a0000,0x453f0000)]
=>0x438ca400 JavaThread "I2P Router Console" daemon [_thread_in_native, id=2408, stack(0x43c60000,0x43cb0000)]
  0x439aa000 JavaThread "JobQueue 1/1" [_thread_blocked, id=3572, stack(0x45250000,0x452a0000)]
  0x43a78400 JavaThread "RouterWatchdog" daemon [_thread_blocked, id=5968, stack(0x45200000,0x45250000)]
  0x43a70c00 JavaThread "Graceful shutdown hook" daemon [_thread_blocked, id=3420, stack(0x451b0000,0x45200000)]
  0x43a62400 JavaThread "Tunnel GW pumper 4/4" daemon [_thread_blocked, id=1812, stack(0x45160000,0x451b0000)]
  0x43a60c00 JavaThread "Tunnel GW pumper 3/4" daemon [_thread_blocked, id=3800, stack(0x45110000,0x45160000)]
  0x43a5fc00 JavaThread "Tunnel GW pumper 2/4" daemon [_thread_blocked, id=5408, stack(0x450c0000,0x45110000)]
  0x43a5f400 JavaThread "Tunnel GW pumper 1/4" daemon [_thread_blocked, id=3916, stack(0x45070000,0x450c0000)]
  0x43a3e000 JavaThread "BWRefiller" daemon [_thread_blocked, id=1204, stack(0x45020000,0x45070000)]
  0x43a17000 JavaThread "SimpleTimer2 4/4" daemon [_thread_blocked, id=5152, stack(0x44fd0000,0x45020000)]
  0x43a15c00 JavaThread "SimpleTimer2 3/4" daemon [_thread_blocked, id=3152, stack(0x44f80000,0x44fd0000)]
  0x43a14400 JavaThread "SimpleTimer2 2/4" daemon [_thread_blocked, id=4216, stack(0x44f30000,0x44f80000)]
  0x43a13800 JavaThread "SimpleTimer2 1/4" daemon [_thread_blocked, id=5492, stack(0x44ee0000,0x44f30000)]
  0x43a07c00 JavaThread "Job Queue Pumper" daemon [_thread_blocked, id=372, stack(0x44e90000,0x44ee0000)]
  0x43a04400 JavaThread "YK Precalc" daemon [_thread_blocked, id=1952, stack(0x44080000,0x440d0000)]
  0x439b0800 JavaThread "PRNG" daemon [_thread_blocked, id=4912, stack(0x44d40000,0x44d90000)]
  0x4394d400 JavaThread "SimpleScheduler 4/4" daemon [_thread_blocked, id=4916, stack(0x43f30000,0x43f80000)]
  0x4394bc00 JavaThread "SimpleScheduler 3/4" daemon [_thread_blocked, id=6008, stack(0x43ee0000,0x43f30000)]
  0x4394a800 JavaThread "SimpleScheduler 2/4" daemon [_thread_blocked, id=4872, stack(0x43e90000,0x43ee0000)]
  0x43948000 JavaThread "SimpleScheduler 1/4" daemon [_thread_blocked, id=2896, stack(0x43e40000,0x43e90000)]
  0x43944400 JavaThread "LogWriter" daemon [_thread_blocked, id=332, stack(0x43df0000,0x43e40000)]
  0x4393e800 JavaThread "Timestamper" daemon [_thread_blocked, id=3528, stack(0x43da0000,0x43df0000)]
  0x0087b800 JavaThread "DestroyJavaVM" [_thread_blocked, id=3180, stack(0x00920000,0x00970000)]
  0x438fa000 JavaThread "Wrapper-Connection" daemon [_thread_in_native, id=5464, stack(0x43cb0000,0x43d00000)]
  0x43888800 JavaThread "Wrapper-Control-Event-Monitor" daemon [_thread_blocked, id=6124, stack(0x43c10000,0x43c60000)]
  0x43409c00 JavaThread "Service Thread" daemon [_thread_blocked, id=5252, stack(0x43710000,0x43760000)]
  0x433e7c00 JavaThread "C1 CompilerThread0" daemon [_thread_in_vm, id=2388, stack(0x436c0000,0x43710000)]
  0x433e6400 JavaThread "Attach Listener" daemon [_thread_blocked, id=5088, stack(0x43670000,0x436c0000)]
  0x433e5000 JavaThread "Signal Dispatcher" daemon [_thread_blocked, id=3848, stack(0x43620000,0x43670000)]
  0x00a9f000 JavaThread "Finalizer" daemon [_thread_blocked, id=1484, stack(0x435d0000,0x43620000)]
  0x00a9a000 JavaThread "Reference Handler" daemon [_thread_blocked, id=3292, stack(0x02b70000,0x02bc0000)]

Other Threads:
  0x00a96c00 VMThread [stack: 0x02b20000,0x02b70000] [id=5580]
  0x4340bc00 WatcherThread [stack: 0x43760000,0x437b0000] [id=3764]

VM state:synchronizing (normal execution)

VM Mutex/Monitor currently owned by a thread:  ([mutex/lock_event])
[0x0087a620] Safepoint_lock - owner thread: 0x00a96c00
[0x0087a688] Threads_lock - owner thread: 0x00a96c00

Heap:
 def new generation   total 9024K, used 950K [0x02c00000, 0x035c0000, 0x18150000)
  eden space 8064K,   0% used [0x02c00000, 0x02c04790, 0x033e0000)
  from space 960K,  97% used [0x034d0000, 0x035b9148, 0x035c0000)
  to   space 960K,   0% used [0x033e0000, 0x033e0000, 0x034d0000)
 tenured generation   total 19872K, used 16017K [0x18150000, 0x194b8000, 0x42c00000)
   the space 19872K,  80% used [0x18150000, 0x190f4648, 0x190f4800, 0x194b8000)
 Metaspace       used 7007K, capacity 7066K, committed 7168K, reserved 7552K

Card table byte_map: [0x42c00000,0x42e10000] byte_map_base: 0x42bea000

Polling page: 0x00970000

CodeCache: size=32768Kb used=1203Kb max_used=1203Kb free=31564Kb
 bounds [0x00aa0000, 0x00bd0000, 0x02aa0000]
 total_blobs=466 nmethods=284 adapters=114
 compilation: enabled

Compilation events (10 events):
Event: 7.065 Thread 0x433e7c00 nmethod 285 0x00bcac88 code [0x00bcad90, 0x00bcae7c]
Event: 7.065 Thread 0x433e7c00  286             sun.util.calendar.CalendarDate::setNormalized (6 bytes)
Event: 7.065 Thread 0x433e7c00 nmethod 286 0x00bcaf48 code [0x00bcb040, 0x00bcb0c0]
Event: 7.075 Thread 0x433e7c00  288   !         sun.misc.Resource::getBytes (242 bytes)
Event: 7.077 Thread 0x433e7c00 nmethod 288 0x00bcb388 code [0x00bcb560, 0x00bcbf84]
Event: 7.098 Thread 0x433e7c00  289             java.lang.String::getChars (16 bytes)
Event: 7.098 Thread 0x433e7c00 nmethod 289 0x00bcc808 code [0x00bcc910, 0x00bcc9fc]
Event: 7.099 Thread 0x433e7c00  290             java.lang.String::concat (47 bytes)
Event: 7.099 Thread 0x433e7c00 nmethod 290 0x00bcca88 code [0x00bccbb0, 0x00bcce24]
Event: 7.138 Thread 0x433e7c00  291   !         java.util.zip.ZipFile$ZipFileInputStream::close (110 bytes)

GC Heap History (10 events):
Event: 1.238 GC heap before
{Heap before GC invocations=3 (full 0):
 def new generation   total 4928K, used 4883K [0x02c00000, 0x03150000, 0x18150000)
  eden space 4416K,  99% used [0x02c00000, 0x0304ca98, 0x03050000)
  from space 512K,  93% used [0x030d0000, 0x031484c8, 0x03150000)
  to   space 512K,   0% used [0x03050000, 0x03050000, 0x030d0000)
 tenured generation   total 10944K, used 6284K [0x18150000, 0x18c00000, 0x42c00000)
   the space 10944K,  57% used [0x18150000, 0x18773388, 0x18773400, 0x18c00000)
 Metaspace       used 5857K, capacity 5914K, committed 6016K, reserved 6528K
Event: 1.243 GC heap after
Heap after GC invocations=4 (full 0):
 def new generation   total 4928K, used 512K [0x02c00000, 0x03150000, 0x18150000)
  eden space 4416K,   0% used [0x02c00000, 0x02c00000, 0x03050000)
  from space 512K, 100% used [0x03050000, 0x030d0000, 0x030d0000)
  to   space 512K,   0% used [0x030d0000, 0x030d0000, 0x03150000)
 tenured generation   total 10944K, used 7163K [0x18150000, 0x18c00000, 0x42c00000)
   the space 10944K,  65% used [0x18150000, 0x1884efe8, 0x1884f000, 0x18c00000)
 Metaspace       used 5857K, capacity 5914K, committed 6016K, reserved 6528K
}
Event: 1.392 GC heap before
{Heap before GC invocations=4 (full 0):
 def new generation   total 4928K, used 4928K [0x02c00000, 0x03150000, 0x18150000)
  eden space 4416K, 100% used [0x02c00000, 0x03050000, 0x03050000)
  from space 512K, 100% used [0x03050000, 0x030d0000, 0x030d0000)
  to   space 512K,   0% used [0x030d0000, 0x030d0000, 0x03150000)
 tenured generation   total 10944K, used 7163K [0x18150000, 0x18c00000, 0x42c00000)
   the space 10944K,  65% used [0x18150000, 0x1884efe8, 0x1884f000, 0x18c00000)
 Metaspace       used 6233K, capacity 6266K, committed 6400K, reserved 6528K
Event: 1.395 GC heap after
Heap after GC invocations=5 (full 0):
 def new generation   total 4928K, used 511K [0x02c00000, 0x03150000, 0x18150000)
  eden space 4416K,   0% used [0x02c00000, 0x02c00000, 0x03050000)
  from space 512K,  99% used [0x030d0000, 0x0314ff60, 0x03150000)
  to   space 512K,   0% used [0x03050000, 0x03050000, 0x030d0000)
 tenured generation   total 10944K, used 7606K [0x18150000, 0x18c00000, 0x42c00000)
   the space 10944K,  69% used [0x18150000, 0x188bdbb8, 0x188bdc00, 0x18c00000)
 Metaspace       used 6233K, capacity 6266K, committed 6400K, reserved 6528K
}
Event: 1.433 GC heap before
{Heap before GC invocations=5 (full 0):
 def new generation   total 4928K, used 4793K [0x02c00000, 0x03150000, 0x18150000)
  eden space 4416K,  96% used [0x02c00000, 0x0302e7d8, 0x03050000)
  from space 512K,  99% used [0x030d0000, 0x0314ff60, 0x03150000)
  to   space 512K,   0% used [0x03050000, 0x03050000, 0x030d0000)
 tenured generation   total 10944K, used 7606K [0x18150000, 0x18c00000, 0x42c00000)
   the space 10944K,  69% used [0x18150000, 0x188bdbb8, 0x188bdc00, 0x18c00000)
 Metaspace       used 6287K, capacity 6330K, committed 6400K, reserved 6528K
Event: 1.435 GC heap after
Heap after GC invocations=6 (full 0):
 def new generation   total 4928K, used 125K [0x02c00000, 0x03150000, 0x18150000)
  eden space 4416K,   0% used [0x02c00000, 0x02c00000, 0x03050000)
  from space 512K,  24% used [0x03050000, 0x0306f518, 0x030d0000)
  to   space 512K,   0% used [0x030d0000, 0x030d0000, 0x03150000)
 tenured generation   total 10944K, used 7755K [0x18150000, 0x18c00000, 0x42c00000)
   the space 10944K,  70% used [0x18150000, 0x188e2d00, 0x188e2e00, 0x18c00000)
 Metaspace       used 6287K, capacity 6330K, committed 6400K, reserved 6528K
}
Event: 1.442 GC heap before
{Heap before GC invocations=6 (full 0):
 def new generation   total 4928K, used 4384K [0x02c00000, 0x03150000, 0x18150000)
  eden space 4416K,  96% used [0x02c00000, 0x03028db0, 0x03050000)
  from space 512K,  24% used [0x03050000, 0x0306f518, 0x030d0000)
  to   space 512K,   0% used [0x030d0000, 0x030d0000, 0x03150000)
 tenured generation   total 10944K, used 7755K [0x18150000, 0x18c00000, 0x42c00000)
   the space 10944K,  70% used [0x18150000, 0x188e2d00, 0x188e2e00, 0x18c00000)
 Metaspace       used 6293K, capacity 6330K, committed 6400K, reserved 6528K
Event: 1.462 GC heap after
Heap after GC invocations=7 (full 1):
 def new generation   total 9024K, used 0K [0x02c00000, 0x035c0000, 0x18150000)
  eden space 8064K,   0% used [0x02c00000, 0x02c00000, 0x033e0000)
  from space 960K,   0% used [0x033e0000, 0x033e0000, 0x034d0000)
  to   space 960K,   0% used [0x034d0000, 0x034d0000, 0x035c0000)
 tenured generation   total 19872K, used 11921K [0x18150000, 0x194b8000, 0x42c00000)
   the space 19872K,  59% used [0x18150000, 0x18cf4638, 0x18cf4800, 0x194b8000)
 Metaspace       used 6293K, capacity 6330K, committed 6400K, reserved 6528K
}
Event: 7.129 GC heap before
{Heap before GC invocations=7 (full 1):
 def new generation   total 9024K, used 8050K [0x02c00000, 0x035c0000, 0x18150000)
  eden space 8064K,  99% used [0x02c00000, 0x033dcb18, 0x033e0000)
  from space 960K,   0% used [0x033e0000, 0x033e0000, 0x034d0000)
  to   space 960K,   0% used [0x034d0000, 0x034d0000, 0x035c0000)
 tenured generation   total 19872K, used 11921K [0x18150000, 0x194b8000, 0x42c00000)
   the space 19872K,  59% used [0x18150000, 0x18cf4638, 0x18cf4800, 0x194b8000)
 Metaspace       used 7007K, capacity 7066K, committed 7168K, reserved 7552K
Event: 7.137 GC heap after
Heap after GC invocations=8 (full 1):
 def new generation   total 9024K, used 932K [0x02c00000, 0x035c0000, 0x18150000)
  eden space 8064K,   0% used [0x02c00000, 0x02c00000, 0x033e0000)
  from space 960K,  97% used [0x034d0000, 0x035b9148, 0x035c0000)
  to   space 960K,   0% used [0x033e0000, 0x033e0000, 0x034d0000)
 tenured generation   total 19872K, used 16017K [0x18150000, 0x194b8000, 0x42c00000)
   the space 19872K,  80% used [0x18150000, 0x190f4648, 0x190f4800, 0x194b8000)
 Metaspace       used 7007K, capacity 7066K, committed 7168K, reserved 7552K
}

Deoptimization events (0 events):
No events

Internal exceptions (10 events):
Event: 7.097 Thread 0x438ca400 Exception <a 'java/security/PrivilegedActionException'> (0x0337a040) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.097 Thread 0x438ca400 Exception <a 'java/security/PrivilegedActionException'> (0x033801f8) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.098 Thread 0x438ca400 Exception <a 'java/security/PrivilegedActionException'> (0x03384270) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.099 Thread 0x438ca400 Exception <a 'java/security/PrivilegedActionException'> (0x03389e40) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.104 Thread 0x438ca400 Exception <a 'java/security/PrivilegedActionException'> (0x0339b878) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.105 Thread 0x438ca400 Exception <a 'java/security/PrivilegedActionException'> (0x033a0148) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.117 Thread 0x439aa000 Exception <a 'java/security/PrivilegedActionException'> (0x033bdbc0) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.118 Thread 0x439aa000 Exception <a 'java/security/PrivilegedActionException'> (0x033c2390) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.119 Thread 0x439aa000 Exception <a 'java/security/PrivilegedActionException'> (0x033c7020) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]
Event: 7.120 Thread 0x437fe400 Exception <a 'java/security/PrivilegedActionException'> (0x033cf888) thrown at [C:\re\workspace\8-2-build-windows-i586-cygwin\jdk8u31\2394\hotspot\src\share\vm\prims\jvm.cpp, line 1312]

Events (10 events):
Event: 7.118 loading class net/i2p/router/startup/BootPeerManagerJob
Event: 7.118 loading class net/i2p/router/startup/BootPeerManagerJob done
Event: 7.119 Thread 0x437f1c00 Thread added: 0x437f1c00
Event: 7.119 loading class net/i2p/util/Addresses
Event: 7.119 loading class net/i2p/util/Addresses done
Event: 7.120 loading class net/i2p/kademlia/KBucketSet
Event: 7.120 loading class net/i2p/kademlia/KBucketSet done
Event: 7.121 Executing VM operation: GenCollectForAllocation
Event: 7.137 Executing VM operation: GenCollectForAllocation done
Event: 7.138 Executing VM operation: RevokeBias


Dynamic libraries:
0x00400000 - 0x00430000 	C:\Documents and Settings\All Users\Application Data\Oracle\Java\javapath\java.exe
0x7c900000 - 0x7c9b2000 	C:\WINDOWS\system32\ntdll.dll
0x7c800000 - 0x7c8f6000 	C:\WINDOWS\system32\kernel32.dll
0x77dd0000 - 0x77e6b000 	C:\WINDOWS\system32\ADVAPI32.dll
0x77e70000 - 0x77f03000 	C:\WINDOWS\system32\RPCRT4.dll
0x77fe0000 - 0x77ff1000 	C:\WINDOWS\system32\Secur32.dll
0x7e410000 - 0x7e4a1000 	C:\WINDOWS\system32\USER32.dll
0x77f10000 - 0x77f59000 	C:\WINDOWS\system32\GDI32.dll
0x773d0000 - 0x774d3000 	C:\WINDOWS\WinSxS\x86_Microsoft.Windows.Common-Controls_6595b64144ccf1df_6.0.2600.6028_x-ww_61e65202\COMCTL32.dll
0x77c10000 - 0x77c68000 	C:\WINDOWS\system32\msvcrt.dll
0x77f60000 - 0x77fd6000 	C:\WINDOWS\system32\SHLWAPI.dll
0x76390000 - 0x763ad000 	C:\WINDOWS\system32\IMM32.DLL
0x629c0000 - 0x629c9000 	C:\WINDOWS\system32\LPK.DLL
0x74d90000 - 0x74dfb000 	C:\WINDOWS\system32\USP10.dll
0x78aa0000 - 0x78b5f000 	C:\Program Files\Java\jre1.8.0_31\bin\msvcr100.dll
0x6d150000 - 0x6d4f4000 	C:\Program Files\Java\jre1.8.0_31\bin\client\jvm.dll
0x71ad0000 - 0x71ad9000 	C:\WINDOWS\system32\WSOCK32.dll
0x71ab0000 - 0x71ac7000 	C:\WINDOWS\system32\WS2_32.dll
0x71aa0000 - 0x71aa8000 	C:\WINDOWS\system32\WS2HELP.dll
0x76b40000 - 0x76b6d000 	C:\WINDOWS\system32\WINMM.dll
0x76bf0000 - 0x76bfb000 	C:\WINDOWS\system32\PSAPI.DLL
0x6ef70000 - 0x6ef7c000 	C:\Program Files\Java\jre1.8.0_31\bin\verify.dll
0x6d9d0000 - 0x6d9f1000 	C:\Program Files\Java\jre1.8.0_31\bin\java.dll
0x6f000000 - 0x6f013000 	C:\Program Files\Java\jre1.8.0_31\bin\zip.dll
0x7c9c0000 - 0x7d1d8000 	C:\WINDOWS\system32\SHELL32.dll
0x769c0000 - 0x76a74000 	C:\WINDOWS\system32\USERENV.dll
0x6ecc0000 - 0x6ecca000 	C:\Program Files\Java\jre1.8.0_31\bin\management.dll
0x6ed60000 - 0x6ed75000 	C:\Program Files\Java\jre1.8.0_31\bin\net.dll
0x71a50000 - 0x71a8f000 	C:\WINDOWS\system32\mswsock.dll
0x662b0000 - 0x66308000 	C:\WINDOWS\system32\hnetcfg.dll
0x58d40000 - 0x58d47000 	C:\WINDOWS\System32\wship6.dll
0x6ed80000 - 0x6ed8f000 	C:\Program Files\Java\jre1.8.0_31\bin\nio.dll
0x43bc0000 - 0x43bf9000 	C:\Program Files\i2p\lib\wrapper.dll
0x71a90000 - 0x71a98000 	C:\WINDOWS\System32\wshtcpip.dll
0x68000000 - 0x68036000 	C:\WINDOWS\system32\rsaenh.dll
0x5b860000 - 0x5b8b6000 	C:\WINDOWS\system32\netapi32.dll
0x76d60000 - 0x76d79000 	C:\WINDOWS\system32\IPHLPAPI.DLL
0x76d40000 - 0x76d58000 	C:\WINDOWS\system32\MPRAPI.dll
0x77cc0000 - 0x77cf2000 	C:\WINDOWS\system32\ACTIVEDS.dll
0x76e10000 - 0x76e35000 	C:\WINDOWS\system32\adsldpc.dll
0x76f60000 - 0x76f8c000 	C:\WINDOWS\system32\WLDAP32.dll
0x76b20000 - 0x76b31000 	C:\WINDOWS\system32\ATL.DLL
0x774e0000 - 0x7761e000 	C:\WINDOWS\system32\ole32.dll
0x77120000 - 0x771ab000 	C:\WINDOWS\system32\OLEAUT32.dll
0x76e80000 - 0x76e8e000 	C:\WINDOWS\system32\rtutils.dll
0x71bf0000 - 0x71c03000 	C:\WINDOWS\system32\SAMLIB.dll
0x77920000 - 0x77a13000 	C:\WINDOWS\system32\SETUPAPI.dll
0x77d00000 - 0x77d33000 	C:\WINDOWS\system32\netman.dll
0x76400000 - 0x765a5000 	C:\WINDOWS\system32\netshell.dll
0x76c00000 - 0x76c2e000 	C:\WINDOWS\system32\credui.dll
0x478c0000 - 0x478ca000 	C:\WINDOWS\system32\dot3api.dll
0x736d0000 - 0x736d6000 	C:\WINDOWS\system32\dot3dlg.dll
0x5dca0000 - 0x5dcc8000 	C:\WINDOWS\system32\OneX.DLL
0x76f50000 - 0x76f58000 	C:\WINDOWS\system32\WTSAPI32.dll
0x76360000 - 0x76370000 	C:\WINDOWS\system32\WINSTA.dll
0x77a80000 - 0x77b17000 	C:\WINDOWS\system32\CRYPT32.dll
0x77b20000 - 0x77b32000 	C:\WINDOWS\system32\MSASN1.dll
0x745b0000 - 0x745d2000 	C:\WINDOWS\system32\eappcfg.dll
0x76080000 - 0x760e5000 	C:\WINDOWS\system32\MSVCP60.dll
0x5dcd0000 - 0x5dcde000 	C:\WINDOWS\system32\eappprxy.dll
0x76ee0000 - 0x76f1c000 	C:\WINDOWS\system32\RASAPI32.dll
0x76e90000 - 0x76ea2000 	C:\WINDOWS\system32\rasman.dll
0x76eb0000 - 0x76edf000 	C:\WINDOWS\system32\TAPI32.dll
0x440e0000 - 0x441c7000 	C:\WINDOWS\system32\WININET.dll
0x441d0000 - 0x441d9000 	C:\WINDOWS\system32\Normaliz.dll
0x78130000 - 0x78264000 	C:\WINDOWS\system32\urlmon.dll
0x441e0000 - 0x443cc000 	C:\WINDOWS\system32\iertutil.dll
0x73030000 - 0x73040000 	C:\WINDOWS\system32\WZCSAPI.DLL
0x7db10000 - 0x7db9c000 	C:\WINDOWS\system32\WZCSvc.DLL
0x76d30000 - 0x76d34000 	C:\WINDOWS\system32\WMI.dll
0x7d4b0000 - 0x7d4d2000 	C:\WINDOWS\system32\DHCPCSVC.DLL
0x76f20000 - 0x76f47000 	C:\WINDOWS\system32\DNSAPI.dll
0x72810000 - 0x7281b000 	C:\WINDOWS\system32\EapolQec.dll
0x726c0000 - 0x726d6000 	C:\WINDOWS\system32\QUtil.dll
0x606b0000 - 0x607bd000 	C:\WINDOWS\system32\ESENT.dll
0x6c940000 - 0x6c94b000 	C:\Program Files\i2p\jcpuid.dll
0x69540000 - 0x69574000 	C:\Program Files\i2p\jbigi.dll
0x6eef0000 - 0x6ef10000 	C:\Program Files\Java\jre1.8.0_31\bin\sunec.dll
0x6ef10000 - 0x6ef19000 	C:\Program Files\Java\jre1.8.0_31\bin\sunmscapi.dll
0x76fc0000 - 0x76fc6000 	C:\WINDOWS\system32\rasadhlp.dll
0x43d00000 - 0x43d19000 	C:\Program Files\i2p\lib\systray4j.dll
0x74720000 - 0x7476c000 	C:\WINDOWS\system32\MSCTF.dll
0x755c0000 - 0x755ee000 	C:\WINDOWS\system32\msctfime.ime
0x76fb0000 - 0x76fb8000 	C:\WINDOWS\System32\winrnr.dll
0x5ad70000 - 0x5ada8000 	C:\WINDOWS\system32\UxTheme.dll
0x751d0000 - 0x751ee000 	C:\WINDOWS\system32\wshbth.dll
0x59a60000 - 0x59b01000 	C:\WINDOWS\system32\dbghelp.dll
0x77c00000 - 0x77c08000 	C:\WINDOWS\system32\VERSION.dll

VM Arguments:
jvm_args: -DloggerFilenameOverride=logs\log-router-@.txt -Dorg.mortbay.http.Version.paranoid=true -Dorg.mortbay.xml.XmlParser.NotValidating=true -Di2p.dir.base=C:\Program Files\i2p -Di2p.dir.config=C:\Documents and Settings\All Users\Application Data\i2p -Xmx1024m -Djava.library.path=C:\Program Files\i2p;C:\Program Files\i2p\lib -Dwrapper.key=gSYLGTZms4EogIx9 -Dwrapper.port=32000 -Dwrapper.jvm.port.min=31000 -Dwrapper.jvm.port.max=31999 -Dwrapper.pid=3304 -Dwrapper.version=3.5.25 -Dwrapper.native_library=wrapper -Dwrapper.arch=x86 -Dwrapper.cpu.timeout=10 -Dwrapper.jvmid=9 
java_command: org.tanukisoftware.wrapper.WrapperSimpleApp net.i2p.router.Router
java_class_path (initial): C:\Program Files\i2p\lib\BOB.jar;C:\Program Files\i2p\lib\commons-el.jar;C:\Program Files\i2p\lib\commons-logging.jar;C:\Program Files\i2p\lib\desktopgui.jar;C:\Program Files\i2p\lib\i2p.jar;C:\Program Files\i2p\lib\i2psnark.jar;C:\Program Files\i2p\lib\i2ptunnel.jar;C:\Program Files\i2p\lib\jasper-compiler.jar;C:\Program Files\i2p\lib\jasper-runtime.jar;C:\Program Files\i2p\lib\javax.servlet.jar;C:\Program Files\i2p\lib\jbigi.jar;C:\Program Files\i2p\lib\jetty-continuation.jar;C:\Program Files\i2p\lib\jetty-deploy.jar;C:\Program Files\i2p\lib\jetty-http.jar;C:\Program Files\i2p\lib\jetty-i2p.jar;C:\Program Files\i2p\lib\jetty-io.jar;C:\Program Files\i2p\lib\jetty-java5-threadpool.jar;C:\Program Files\i2p\lib\jetty-rewrite-handler.jar;C:\Program Files\i2p\lib\jetty-security.jar;C:\Program Files\i2p\lib\jetty-servlet.jar;C:\Program Files\i2p\lib\jetty-servlets.jar;C:\Program Files\i2p\lib\jetty-sslengine.jar;C:\Program Files\i2p\lib\jetty-start.jar;C:\Program Files\i2p\lib\jetty-util.jar;C:\Program Files\i2p\lib\jetty-webapp.jar;C:\Program Files\i2p\lib\jetty-xml.jar;C:\Program Files\i2p\lib\jrobin.jar;C:\Program Files\i2p\lib\jstl.jar;C:\Program Files\i2p\lib\mstreaming.jar;C:\Program Files\i2p\lib\org.mortbay.jetty.jar;C:\Program Files\i2p\lib\org.mortbay.jmx.jar;C:\Program Files\i2p\lib\router.jar;C:\Program Files\i2p\lib\routerconsole.jar;C:\Program Files\i2p\lib\sam.jar;C:\Program Files\i2p\lib\standard.jar;C:\Program Files\i2p\lib\streaming.jar;C:\Program Files\i2p\lib\systray4j.jar;C:\Program Files\i2p\lib\systray.jar;C:\Program Files\i2p\lib\wrapper.jar
Launcher Type: SUN_STANDARD

Environment Variables:
PATH=(strippedout)
USERNAME=(strippedout)
OS=Windows_NT
PROCESSOR_IDENTIFIER=x86 Family 6 Model 23 Stepping 10, GenuineIntel



---------------  S Y S T E M  ---------------

OS: Windows XP Build 2600 Service Pack 3

CPU:total 1 (1 cores per cpu, 1 threads per core) family 6 model 23 stepping 10, cmov, cx8, fxsr, mmx, sse, sse2, sse3, ssse3, tsc

Memory: 4k page, physical 2061328k(1026404k free), swap 3994364k(3007448k free)

vm_info: Java HotSpot(TM) Client VM (25.31-b07) for windows-x86 JRE (1.8.0_31-b13), built on Dec 17 2014 20:46:12 by "java_re" with MS VC++ 10.0 (VS2010)

time: Sun Apr 12 22:47:01 2015
elapsed time: 7 seconds (0d 0h 0m 7s)

comment:2 Changed 4 years ago by djjeshk

6 peers were detected running 0.9.19, most likely active developers. zzz.i2p is not responding or responding slowly. forums.i2p is working, killyourtv.i2p is working, forum.i2p is working.

comment:3 Changed 4 years ago by echelon

Moin

The Java bugs we cannot control, file a bugreport against Oracle. But I suggest to NO MORE use Windows XP as it is REALLY a bug hog and unsecure and very very bad habit to still use it with network.
Message delay is always changing, you need to observe it a few days until you can tell anything of value, as it still includes lots of network properties.

comment:4 Changed 4 years ago by dg

Seconding what echelon said, and adding some of my own points.. you should really:

  • Update your OS. This does matter, it affects how Java and other programs interface with the network.
  • Update your JRE. It's Java 8, but it's not latest Java 8.
  • Report this crash to Oracle. The crash at least is not an I2P issue.
  • Stop using "sptimesync" to work around "bugs" in your OS. Yes, Windows XP is buggy, but I doubt its time sync is that buggy. It's a non issue because I2P syncs its time internally.
  • Stop using any other external programs, especially if they involve killing/suspending/restarting threads. Any issues with CPU should be reported with a vanilla I2P (or, if with patches, included, but ideally & preferably reproduced without these patches) and without external intervention such as this. Interference with threads is not, and will not, be supported by any project. We are not an exception.
  • To that end, we are more than happy to accept bug reports, but provide us with useful information too. We can help you to determine which threads are problematic, but not if you are performing your own (well-intentioned) potentially harmful experiments on the router.

comment:5 Changed 4 years ago by djjeshk

You are right that I2P 0.9.19 is too old for Windows XP and I can say that 0.9.18.18 is the lastest version that behaves normally in Windows XP. It might be a good idea to not let XP users upgrade automatically to 0.9.19.

dg, your first three points are on topic, but second three is out of this issue.
Do you have proof that I did use sptimesync at time when this happened? You have not because issue text does not contain sptimesync.
Do you have proof that I used such programs at crash? I only asked to I2P router to upgrade and downgrade itself, so no, you have not. I do not know any other I2P patching than regular updates and test upgrades available at http://update.killyourtv.i2p/ and it is recommended to test these.
Yes, I flooded you with lot of information, but I did not made any potentially harmful experiments on the router at time when this issue happened. Only experiments I could made are some strong words thrown to failing router that could freeze system for some seconds.
Be serious and be on topic at this issue.

Last edited 4 years ago by djjeshk (previous) (diff)

comment:6 Changed 4 years ago by djjeshk

I upgraded my OS to windows 7 and this java bug appeared again.

Version 0, edited 4 years ago by djjeshk (next)

comment:7 Changed 4 years ago by djjeshk

  • Priority changed from blocker to major
  • Resolution set to wontfix
  • Status changed from new to closed

Freeze and high delay issue does not exists for Windows 7. If you are not supporting Windows XP, there is no reason to leave this open.
PS. Message delays are decreased for about 5-10 times

Last edited 4 years ago by djjeshk (previous) (diff)
Note: See TracTickets for help on using tickets.