Wildfire Startup Extremely Slow

Openfire Openfire Support
1

Hello folks,

I was wondering if anyone might be able to help me troubleshoot a recent problem I noticed. It seems as thought the latest versions of Wildfire are taking what seems to be an overly long time to startup. Here, check this out.

2007.03.02 22:16:49 Multi User Chat domain: conference.jabber.mydomain.com

2007.03.02 22:16:50 Publish-Subscribe domain: pubsub.jabber.mydomain.com

2007.03.02 22:19:59 Started plain (unencrypted) socket on port: 5222

2007.03.02 22:20:02 Started SSL (encrypted) socket on port: 5223

2007.03.02 22:20:02 Wildfire 3.2.2

As you can see from the output of the info.log above, it takes about 3 minutes to startup. Is that normal or might I have something mis-configured?

2

3 minutes does seem extraordinarily long. Those same steps in my server log take about 5 seconds or so on our P4 2.8Mhz, 1Gb RAM, Win2003 Server.

Can you describe your operating environment a bit more? ie. Server Hardware Spec (Processor, Memory), Operating System, External Database?, User authentication type (LDAP?), etc.

Also try enabling debugging under the Server > Logs > Debug and then restarting your Wildfire service/daemon. The Wildfire logfiles in the \logs directory are timestamped so they should give you a clue as to where the delay is occurring.

3

Hey stuartbain,

Yeah, 5 seconds seems about right based on how long it took before, not sure what’‘s going on now. To give you an idea of the operating enviroment, here’‘s what it’'s running on.

O/S: Ubuntu Edgy

Kernel: 2.6.17-11-server #2 SMP

Processor: Pentium 3 1GHz

Memory: 128MB

Hard Disk: 160GB SATA RAID 1

Database: Embedded

Also, I just turned on debugging. Included below is the output from the debug.log and the info.log when I restarted wildfire.

– info.log –

2007.03.03 10:42:40 Multi User Chat domain: conference.jabber.mydomain.com

2007.03.03 10:42:40 Publish-Subscribe domain: pubsub.jabber.mydomain.com

2007.03.03 10:45:50 Started plain (unencrypted) socket on port: 5222

2007.03.03 10:45:52 Started SSL (encrypted) socket on port: 5223

2007.03.03 10:45:53 Wildfire 3.2.2

2007.03.03 10:46:00 Admin console listening at:

http://jabber.mydomain.com:9090

https://jabber.mydomain.com:9091

– end info.log –

– debug.log –

2007.03.03 10:45:53 Loading plugin admin

2007.03.03 10:45:53 Logging to {} via {}

2007.03.03 10:45:53 jetty-6.1.x

2007.03.03 10:45:53 started {}

2007.03.03 10:45:53 Checking Resource aliases

2007.03.03 10:45:54 TLD search {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 starting {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 starting {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 starting {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 starting {}

2007.03.03 10:45:55 starting {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 Parent class loader is: ContextLoader@14113863

2007.03.03 10:45:55 Compilation classpath initialized: /opt/wildfire/plugins/admin/webapp/WEB-INF/lib/dwr.jar:/opt/wildfire/plugins/ad min/webapp/WEB-INF/lib/admin-jsp.jar:/tmp/Jetty_0_0_0_0_9090_webapp____-dnguxu/j sp:null

2007.03.03 10:45:55 Scratch dir for the JSP engine is: /tmp/Jetty_0_0_0_0_9090_webapp____-dnguxu/jsp

2007.03.03 10:45:55 IMPORTANT: Do not modify the generated servlets

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:55 started {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 Java version: 1.5.0_08

2007.03.03 10:45:56 Java vendor: Sun Microsystems Inc.

2007.03.03 10:45:56 Java class path: /opt/wildfire/.install4j/i4jruntime.jar:/opt/wildfire/lib/activation.jar:/opt/w ildfire/lib/bouncycastle.jar:/opt/wildfire/lib/commons-el.jar:/opt/wildfire/lib/ hsqldb.jar:/opt/wildfire/lib/jasper-compiler.jar:/opt/wildfire/lib/jasper-runtim e.jar:/opt/wildfire/lib/jdic.jar:/opt/wildfire/lib/jtds.jar:/opt/wildfire/lib/ma il.jar:/opt/wildfire/lib/mysql.jar:/opt/wildfire/lib/postgres.jar:/opt/wildfire/ lib/servlet.jar:/opt/wildfire/lib/startup.jar:/opt/wildfire/lib/wildfire.jar

2007.03.03 10:45:56 Operating system name: Linux

2007.03.03 10:45:56 Operating system architecture: i386

2007.03.03 10:45:56 Operating system version: 2.6.17-11-server

2007.03.03 10:45:56 SUN 1.5: SUN (DSA key/parameter generation; DSA signing; SHA-1, MD5 digests; SecureRandom; X.509 certificates; JKS keystore; PKIX CertPathValidator; PKIX CertPathBuilder; LDAP, Collection CertStores)

2007.03.03 10:45:56 SunRsaSign 1.5: Sun RSA signature provider

2007.03.03 10:45:56 SunJSSE 1.5: Sun JSSE provider(PKCS12, SunX509 key/trust factories, SSLv3, TLSv1)

2007.03.03 10:45:56 SunJCE 1.5: SunJCE Provider (implements RSA, DES, Triple DES, AES, Blowfish, ARCFOUR, RC2, PBE, Diffie-Hellman, HMAC)

2007.03.03 10:45:56 SunJGSS 1.0: Sun (Kerberos v5)

2007.03.03 10:45:56 SunSASL 1.5: Sun SASL provider(implements client mechanisms for: DIGEST-MD5, GSSAPI, EXTERNAL, PLAIN, CRAM-MD5; server mechanisms for: DIGEST-MD5, GSSAPI, CRAM-MD5)

2007.03.03 10:45:56 BC 1.35: BouncyCastle Security Provider v1.35

2007.03.03 10:45:56 Set parameter http.useragent = Jakarta Commons-HttpClient/3.0

2007.03.03 10:45:56 Set parameter http.protocol.version = HTTP/1.1

2007.03.03 10:45:56 Set parameter http.connection-manager.class = class org.apache.commons.httpclient.SimpleHttpConnectionManager

2007.03.03 10:45:56 Set parameter http.protocol.cookie-policy = rfc2109

2007.03.03 10:45:56 Set parameter http.protocol.element-charset = US-ASCII

2007.03.03 10:45:56 Set parameter http.protocol.content-charset = ISO-8859-1

2007.03.03 10:45:56 Set parameter http.method.retry-handler = org.apache.commons.httpclient.DefaultHttpMethodRetryHandler@16b9e62

2007.03.03 10:45:56 Set parameter http.dateparser.patterns = EEE, dd MMM yyyy HH:mm:ss zzz, EEEE, dd-MMM-yy HH:mm:ss zzz, EEE MMM d HH:mm:ss yyyy, EEE, dd-MMM-yyyy HH:mm:ss z, EEE, dd-MMM-yyyy HH-mm-ss z, EEE, dd MMM yy HH:mm:ss z, EEE dd-MMM-yyyy HH:mm:ss z, EEE dd MMM yyyy HH:mm:ss z, EEE dd-MMM-yyyy HH-mm-ss z, EEE dd-MMM-yy HH:mm:ss z, EEE dd MMM yy HH:mm:ss z, EEE,dd-MMM-yy HH:mm:ss z, EEE,dd-MMM-yyyy HH:mm:ss z, EEE, dd-MM-yyyy HH:mm:ss z

2007.03.03 10:45:56 Set parameter http.connection.timeout = 2000

2007.03.03 10:45:56 Set parameter http.socket.timeout = 2000

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 starting {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 starting {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 starting {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:56 starting {}

2007.03.03 10:45:56 started {}

2007.03.03 10:45:57 Starting org.mortbay.jetty.AbstractConnector$Acceptor@df2940

2007.03.03 10:45:57 Started {}

2007.03.03 10:45:57 started {}

2007.03.03 10:46:00 Starting org.mortbay.jetty.AbstractConnector$Acceptor@19cc1b

2007.03.03 10:46:00 Started {}

2007.03.03 10:46:00 started {}

2007.03.03 10:46:00 started {}

2007.03.03 10:46:00 Loading plugin search

2007.03.03 10:46:00 Registering component for domain: search

2007.03.03 10:46:00 Component registered for domain: search

– end debug.log –

Note that the debug output doesn’'t start until 10:45:53 while the info.log stuff starts at 10:42:40. Any ideas what might be going on?

Message was edited by: walkchalk

4

Hi,

it could help to run “vmstat 1” during startup to check if the CPU is used heavyly.

Add “-XX:+PrintGCDetails -Xloggc:$/WF/logs/gc.log” to INSTALL4J_ADD_VM_PARAMS to track every garbage collection and allocation failure.

You could also create some thread dumps using `kill -3 wildfire-pid´ to collect more information, especially if the CPU is using 100%. So the running threads can be identified very easy.

LG

5

lt2000,

I took you suggestion and grabbed some more details. Although to be fair, I’'m not sure how to really parse the output, but perhaps you can shed some light there. This was all captured during startup. The “vmstat 1” output is truncated, but I grabbed the section that seemed to be most relevant and common during the startup. Let me know what you think.

VMSTAT

procs -

memory----

-swap -

io---- -system-- -

cpu----

r b swpd free buff cache si so bi bo in cs us sy id wa

0 0 4208 30836 5960 40672 0 0 0 0 105 49 0 0 100 0

0 0 4208 30836 5960 40672 0 0 0 0 107 54 0 0 100 0

0 0 4208 30836 5968 40664 0 0 0 340 231 81 0 1 96 3

0 0 4208 30836 5968 40664 0 0 0 0 103 46 0 0 100 0

0 0 4208 30836 5968 40664 0 0 0 19 118 66 0 0 100 0

0 0 4208 30836 5968 40664 0 0 0 0 103 44 0 0 100 0

0 0 4208 30836 5968 40664 0 0 0 0 103 45 0 0 100 0

0 0 4208 30836 5972 40660 0 0 0 20 117 59 0 0 100 0

WILDFIRE PROCESS:

wildfire 8613 51.6 26.3 279236 33164 pts/0 Sl 11:53 0:05 /usr/bin/java -server -Dinstall4j.jvmDir=/usr -Dexe4j.moduleName=/opt/wildfire/bin/wildfire -XX:+PrintGCDetails -Xloggc:/opt/wildfire/logs/gc.log -classpath /opt/wildfire/.install4j/i4jruntime.jar:/opt/wildfire/lib/activation.jar:/opt/w ildfire/lib/bouncycastle.jar:/opt/wildfire/lib/commons-el.jar:/opt/wildfire/lib/ hsqldb.jar:/opt/wildfire/lib/jasper-compiler.jar:/opt/wildfire/lib/jasper-runtim e.jar:/opt/wildfire/lib/jdic.jar:/opt/wildfire/lib/jtds.jar:/opt/wildfire/lib/ma il.jar:/opt/wildfire/lib/mysql.jar:/opt/wildfire/lib/postgres.jar:/opt/wildfire/ lib/servlet.jar:/opt/wildfire/lib/startup.jar:/opt/wildfire/lib/wildfire.jar com.install4j.runtime.Launcher start org.jivesoftware.wildfire.starter.ServerStarter false false /opt/wildfire/bin/…/logs/stderror.log /opt/wildfire/bin/…/logs/stdoutt.log true true false true true 0 0 20 20 Arial 0,0,0 8 500 version 3.2.2 20 40 Arial 0,0,0 8 500 -1 -DwildfireHome=/opt/wildfire -Dwildfire.lib.dir=/opt/wildfire/lib

GC.LOG:

0.350: [GC 0.350: DefNew: 499K->64K(576K), 0.0096600 secs 499K->162K(1984K), 0.0098730 secs]

0.516: [GC 0.516: DefNew: 576K->64K(576K), 0.0062380 secs 674K->306K(1984K), 0.0064410 secs]

0.894: [GC 0.895: DefNew: 576K->56K(576K), 0.0058300 secs 818K->361K(1984K), 0.0060280 secs]

1.200: [GC 1.201: DefNew: 506K->64K(576K), 0.0092100 secs 812K->466K(1984K), 0.0094110 secs]

1.345: [GC 1.345: DefNew: 570K->35K(576K), 0.0058940 secs 972K->561K(1984K), 0.0060790 secs]

1.572: [GC 1.572: DefNew: 547K->19K(576K), 0.0045940 secs 1073K->569K(1984K), 0.0047630 secs]

1.767: [GC 1.767: DefNew: 531K->64K(576K), 0.0074440 secs 1081K->745K(1984K), 0.0076450 secs]

1.836: [GC 1.836: DefNew: 537K->46K(576K), 0.0045580 secs 1218K->967K(1984K), 0.0047530 secs]

1.843: [GC 1.843: DefNew: 519K->39K(576K), 0.0048000 secs 1440K->1436K(1984K), 0.0050200 secs]

1.912: [GC 1.912: DefNew: 519K->47K(576K), 0.0039720 secs1.917: Tenured: 1671K->1718K(1792K), 0.0785940 secs 1917K->1718K(2368K), 0.0829130 secs]

2.127: [GC 2.128: DefNew: 505K->64K(576K), 0.0038520 secs 2224K->1825K(3444K), 0.0040430 secs]

2.308: [GC 2.308: DefNew: 575K->59K(576K), 0.0062400 secs 2337K->1881K(3444K), 0.0064270 secs]

2.655: [GC 2.655: DefNew: 564K->64K(576K), 0.0052060 secs 2386K->2044K(3444K), 0.0054150 secs]

2.719: [GC 2.719: DefNew: 576K->43K(576K), 0.0031980 secs 2556K->2131K(3444K), 0.0033870 secs]

2.925: [GC 2.925: DefNew: 555K->48K(576K), 0.0035820 secs 2643K->2179K(3444K), 0.0037580 secs]

3.132: [GC 3.132: DefNew: 560K->34K(576K), 0.0063060 secs 2691K->2178K(3444K), 0.0064790 secs]

3.217: [GC 3.217: DefNew: 516K->52K(576K), 0.0028520 secs 2660K->2214K(3444K), 0.0030280 secs]

3.324: [GC 3.324: DefNew: 564K->18K(576K), 0.0031310 secs 2726K->2188K(3444K), 0.0032930 secs]

3.437: [GC 3.437: DefNew: 530K->64K(576K), 0.0040760 secs 2700K->2256K(3444K), 0.0042620 secs]

3.722: [GC 3.722: DefNew: 575K->64K(576K), 0.0051290 secs 2768K->2350K(3444K), 0.0053320 secs]

3.836: [GC 3.836: DefNew: 576K->24K(576K), 0.0057180 secs 2862K->2372K(3444K), 0.0059010 secs]

3.957: [GC 3.957: DefNew: 536K->37K(576K), 0.0035740 secs 2884K->2386K(3444K), 0.0037470 secs]

4.106: [GC 4.106: DefNew: 549K->64K(576K), 0.0076060 secs 2898K->2698K(3444K), 0.0078540 secs]

4.264: [GC 4.264: DefNew: 576K->64K(576K), 0.0092540 secs4.273: Tenured: 3063K->3054K(3124K), 0.1323030 secs 3210K->3054K(3700K), 0.1419910 secs]

4.509: [GC 4.509: DefNew: 576K->64K(640K), 0.0050480 secs 3630K->3217K(5732K), 0.0052500 secs]

4.814: [GC 4.814: DefNew: 639K->22K(640K), 0.0041470 secs 3792K->3238K(5732K), 0.0043310 secs]

4.927: [GC 4.927: DefNew: 591K->49K(640K), 0.0034710 secs 3807K->3265K(5732K), 0.0036450 secs]

5.163: [GC 5.164: DefNew: 623K->64K(640K), 0.0043210 secs 3839K->3396K(5732K), 0.0045170 secs]

5.273: [GC 5.274: DefNew: 609K->64K(640K), 0.0050730 secs 3942K->3460K(5732K), 0.0052620 secs]

5.461: [GC 5.461: DefNew: 640K->61K(640K), 0.0049350 secs 4036K->3477K(5732K), 0.0051130 secs]

5.618: [GC 5.618: DefNew: 637K->34K(640K), 0.0060420 secs 4053K->3478K(5732K), 0.0062210 secs]

142.190: [GC 142.190: DefNew: 610K->22K(640K), 0.0039740 secs 4054K->3496K(5732K), 0.0041570 secs]

194.679: [GC 194.679: DefNew: 578K->44K(640K), 0.0034410 secs 4053K->3519K(5732K), 0.0036200 secs]

195.160: [GC 195.161: DefNew: 620K->64K(640K), 0.0096860 secs 4095K->3612K(5732K), 0.0098900 secs]

195.241: [GC 195.241: DefNew: 640K->64K(640K), 0.0052470 secs 4188K->3741K(5732K), 0.0054600 secs]

195.589: [GC 195.590: DefNew: 640K->63K(640K), 0.0066800 secs 4317K->3983K(5732K), 0.0068950 secs]

195.660: [GC 195.660: DefNew: 639K->63K(640K), 0.0055640 secs 4559K->4189K(5732K), 0.0057870 secs]

196.109: [GC 196.109: DefNew: 563K->63K(640K), 0.0051430 secs 4689K->4334K(5732K), 0.0053740 secs]

196.145: [GC 196.145: DefNew: 639K->32K(640K), 0.0046220 secs 4910K->4574K(5732K), 0.0048420 secs]

196.233: [GC 196.233: DefNew: 608K->63K(640K), 0.0026070 secs 5150K->4641K(5732K), 0.0028080 secs]

196.262: [GC 196.262: DefNew: 639K->64K(640K), 0.0031090 secs 5217K->4710K(5732K), 0.0033190 secs]

196.302: [GC 196.302: DefNew: 640K->64K(640K), 0.0041110 secs 5286K->4799K(5732K), 0.0043160 secs]

196.479: [GC 196.479: DefNew: 640K->64K(640K), 0.0052510 secs 5375K->5003K(5732K), 0.0054760 secs]

196.588: [GC 196.588: DefNew: 637K->64K(640K), 0.0078800 secs196.597: Tenured: 5190K->5072K(5220K), 0.2109110 secs 5577K->5072K(5860K), 0.2192440 secs]

197.619: [GC 197.619: DefNew: 960K->63K(1024K), 0.0078160 secs 6032K->5322K(9480K), 0.0080340 secs]

197.876: [GC 197.876: DefNew: 1023K->64K(1024K), 0.0053820 secs 6282K->5398K(9480K), 0.0055780 secs]

198.064: [GC 198.064: DefNew: 1024K->60K(1024K), 0.0078640 secs 6358K->5457K(9480K), 0.0080690 secs]

198.764: [GC 198.764: DefNew: 997K->57K(1024K), 0.0061970 secs 6394K->5511K(9480K), 0.0064000 secs]

198.933: [GC 198.934: DefNew: 1017K->58K(1024K), 0.0053000 secs 6471K->5536K(9480K), 0.0054950 secs]

199.005: [GC 199.006: DefNew: 1018K->0K(1024K), 0.0030200 secs 6496K->5531K(9480K), 0.0032070 secs]

199.080: [GC 199.080: DefNew: 960K->0K(1024K), 0.0014160 secs 6491K->5531K(9480K), 0.0015800 secs]

199.230: [GC 199.230: DefNew: 960K->41K(1024K), 0.0023080 secs 6491K->5572K(9480K), 0.0024830 secs]

199.263: [GC 199.263: DefNew: 1001K->1K(1024K), 0.0019880 secs 6532K->5572K(9480K), 0.0021690 secs]

199.332: [GC 199.332: DefNew: 961K->64K(1024K), 0.0049050 secs 6532K->5646K(9480K), 0.0050930 secs]

200.131: [GC 200.131: DefNew: 1024K->64K(1024K), 0.0071730 secs 6606K->5837K(9480K), 0.0074010 secs]

200.326: [GC 200.326: DefNew: 1021K->64K(1024K), 0.0094950 secs 6794K->5969K(9480K), 0.0097070 secs]

200.533: [GC 200.533: DefNew: 1024K->64K(1024K), 0.0056540 secs 6929K->6026K(9480K), 0.0058530 secs]

200.620: [GC 200.620: DefNew: 1019K->64K(1024K), 0.0053800 secs 6982K->6187K(9480K), 0.0055890 secs]

200.832: [GC 200.832: DefNew: 985K->12K(1024K), 0.0030980 secs 7109K->6172K(9480K), 0.0032820 secs]

200.936: [GC 200.936: DefNew: 965K->57K(1024K), 0.0027380 secs 7125K->6217K(9480K), 0.0029230 secs]

201.040: [GC 201.040: DefNew: 1012K->46K(1024K), 0.0024180 secs 7172K->6207K(9480K), 0.0025970 secs]

201.132: [Full GC 201.132: Tenured: 6161K->4834K(8456K), 0.3663510 secs 6857K->4834K(9480K), , 0.3666240 secs]

201.500: [Full GC 201.501: Tenured: 4834K->4834K(8456K), 0.2443770 secs 4836K->4834K(9544K), , 0.2445860 secs]

202.398: [GC 202.398: DefNew: 1024K->35K(1088K), 0.0039270 secs 5858K->4869K(9544K), 0.0041070 secs]

202.662: [GC 202.662: DefNew: 1059K->64K(1088K), 0.0090150 secs 5893K->4985K(9544K), 0.0092300 secs]

203.081: [GC 203.082: DefNew: 1085K->64K(1088K), 0.0046900 secs 6007K->5057K(9544K), 0.0049030 secs]

203.163: [GC 203.163: DefNew: 1088K->29K(1088K), 0.0029080 secs 6081K->5072K(9544K), 0.0031000 secs]

203.321: [GC 203.322: DefNew: 1053K->64K(1088K), 0.0055230 secs 6096K->5198K(9544K), 0.0057330 secs]

203.880: [GC 203.880: DefNew: 1088K->63K(1088K), 0.0051180 secs 6222K->5256K(9544K), 0.0053290 secs]

203.945: [GC 203.946: DefNew: 1087K->64K(1088K), 0.0089310 secs 6280K->5698K(9544K), 0.0091650 secs]

204.005: [GC 204.006: DefNew: 1088K->64K(1088K), 0.0064050 secs 6722K->6026K(9544K), 0.0066350 secs]

204.192: [GC 204.192: DefNew: 1088K->63K(1088K), 0.0049590 secs 7050K->6145K(9544K), 0.0051900 secs]

204.225: [GC 204.225: DefNew: 1087K->64K(1088K), 0.0054570 secs 7169K->6339K(9544K), 0.0056920 secs]

204.328: [GC 204.328: DefNew: 1080K->64K(1088K), 0.0039800 secs 7356K->6467K(9544K), 0.0041990 secs]

204.379: [GC 204.379: DefNew: 1087K->16K(1088K), 0.0025330 secs 7491K->6476K(9544K), 0.0027290 secs]

204.427: [GC 204.427: DefNew: 1040K->37K(1088K), 0.0030880 secs 7500K->6498K(9544K), 0.0032690 secs]

204.478: [GC 204.478: DefNew: 1054K->27K(1088K), 0.0021080 secs 7515K->6499K(9544K), 0.0022860 secs]

204.545: [GC 204.545: DefNew: 1050K->41K(1088K), 0.0021400 secs 7522K->6513K(9544K), 0.0023210 secs]

204.601: [GC 204.601: DefNew: 1065K->39K(1088K), 0.0022560 secs 7537K->6523K(9544K), 0.0024360 secs]

204.736: [GC 204.736: DefNew: 1063K->40K(1088K), 0.0022760 secs 7547K->6535K(9544K), 0.0025390 secs]

204.784: [GC 204.784: DefNew: 1061K->39K(1088K), 0.0022500 secs 7556K->6549K(9544K), 0.0024350 secs]

204.834: [GC 204.835: DefNew: 1063K->45K(1088K), 0.0023310 secs 7573K->6565K(9544K), 0.0025170 secs]

204.883: [GC 204.883: DefNew: 1069K->34K(1088K), 0.0023340 secs 7589K->6579K(9544K), 0.0025190 secs]

204.931: [GC 204.931: DefNew: 1058K->32K(1088K), 0.0027640 secs 7603K->6594K(9544K), 0.0029450 secs]

205.115: [GC 205.115: DefNew: 1056K->33K(1088K), 0.0022060 secs 7618K->6610K(9544K), 0.0023900 secs]

205.166: [GC 205.166: DefNew: 1057K->31K(1088K), 0.0021550 secs 7634K->6624K(9544K), 0.0023350 secs]

205.211: [GC 205.212: DefNew: 1055K->42K(1088K), 0.0020480 secs 7647K->6635K(9544K), 0.0022310 secs]

205.325: [GC 205.325: DefNew: 1066K->64K(1088K), 0.0058300 secs 7659K->6855K(9544K), 0.0060590 secs]

205.630: [GC 205.630: DefNew: 1088K->10K(1088K), 0.0028100 secs 7879K->6810K(9544K), 0.0029910 secs]

206.078: [GC 206.079: DefNew: 1034K->64K(1088K), 0.0090350 secs 7834K->7021K(9544K), 0.0092450 secs]

6

Hi,

with -cpu- (us sy id wa) = (0 0 100 0) I see that your server is idle, no user, system or wait times. I assume that you see this during the 3 minutes you are waiting during startup.

The GC log shows at lot of allocation failes but all complete within “0.003 secs”, the Full GC’'s need “0.3 secs” so if I add all GC activity times I may get 2 seconds and not 3 minutes.

It seems that the java process is waiting for some locked resources.

The kill -3 pid output should be written to nohup.out, do you find there some thread dumps?

LG

7

lt2000,

I did a few “kill -3 PID” during both the startup and after it’‘s totally started. Here’'s the output, let me know if you see anything strange.

– nohup.out –

Full thread dump Java HotSpot™ Server VM (1.5.0_08-b03 mixed mode):

“Update Manager” daemon prio=1 tid=0x08478ee0 nid=0x22fb waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.wildfire.update.UpdateManager$1.waitForNextCheck(UpdateManager .java:153)

at org.jivesoftware.wildfire.update.UpdateManager$1.run(UpdateManager.java:106)

“pool-1-thread-1” prio=1 tid=0x084799c8 nid=0x22fa runnable

at java.net.PlainSocketImpl.initProto(Native Method)

at java.net.PlainSocketImpl.(ServerSocket.java:178)

at org.jivesoftware.wildfire.filetransfer.proxy.ProxyConnectionManager$1.run(Proxy ConnectionManager.java:84)

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)

at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)

at java.util.concurrent.FutureTask.run(FutureTask.java:123)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PubSub maintenance” prio=1 tid=0x084e2568 nid=0x22f9 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d8b418> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d8b418> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Auditor” prio=1 tid=0x0846a990 nid=0x22f7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d722b0> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d722b0> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“MUC cleanup” prio=1 tid=0x084dba50 nid=0x22f6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d65998> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d65998> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Sessions cleanup” prio=1 tid=0x08545730 nid=0x22f5 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9c564f8> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9c564f8> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Thread-2” daemon prio=1 tid=0x08551128 nid=0x22f4 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.database.ConnectionPool.run(ConnectionPool.java:374)

at java.lang.Thread.run(Thread.java:595)

“HSQLDB Timer @1394894” daemon prio=1 tid=0x085a1f00 nid=0x22f0 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)
    at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
  • locked <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)

at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)

at java.lang.Thread.run(Thread.java:595)

“Thread-0” daemon prio=1 tid=0x08127318 nid=0x22ee waiting on condition

at java.lang.Thread.sleep(Native Method)

at com.install4j.runtime.Launcher$StopWatcherThread.run(Unknown Source)

“Low Memory Detector” daemon prio=1 tid=0x080f8fa8 nid=0x22ec runnable

“CompilerThread1” daemon prio=1 tid=0x080f7ad8 nid=0x22eb waiting on condition

“CompilerThread0” daemon prio=1 tid=0x080f6a30 nid=0x22ea waiting on condition

“AdapterThread” daemon prio=1 tid=0x080f58b8 nid=0x22e9 waiting on condition

“Signal Dispatcher” daemon prio=1 tid=0x080f4a20 nid=0x22e8 runnable

“Finalizer” daemon prio=1 tid=0x080e9558 nid=0x22e7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
  • locked <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

“Reference Handler” daemon prio=1 tid=0x080e9010 nid=0x22e6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f98> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:474)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
  • locked <0xa9a58f98> (a java.lang.ref.Reference$Lock)

“main” prio=1 tid=0x0805d568 nid=0x22e3 in Object.wait()

at java.net.Socket.setImpl(Socket.java:431)

at java.net.Socket.(XMPPServer.java:148)

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessor Impl.java:39)

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructor AccessorImpl.java:27)

at java.lang.reflect.Constructor.newInstance(Constructor.java:494)

at java.lang.Class.newInstance0(Class.java:350)

at java.lang.Class.newInstance(Class.java:303)

at org.jivesoftware.wildfire.starter.ServerStarter.start(ServerStarter.java:93)

at org.jivesoftware.wildfire.starter.ServerStarter.main(ServerStarter.java:49)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.ja va:25)

at java.lang.reflect.Method.invoke(Method.java:585)

at com.exe4j.runtime.LauncherEngine.launch(Unknown Source)

at com.install4j.runtime.Launcher.main(Unknown Source)

“VM Thread” prio=1 tid=0x080e6ad0 nid=0x22e5 runnable

“VM Periodic Task Thread” prio=1 tid=0x080fa480 nid=0x22ed waiting on condition

Full thread dump Java HotSpot™ Server VM (1.5.0_08-b03 mixed mode):

“Update Manager” daemon prio=1 tid=0x08478ee0 nid=0x22fb waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.wildfire.update.UpdateManager$1.waitForNextCheck(UpdateManager .java:153)

at org.jivesoftware.wildfire.update.UpdateManager$1.run(UpdateManager.java:106)

“pool-1-thread-1” prio=1 tid=0x084799c8 nid=0x22fa runnable

at java.net.PlainSocketImpl.initProto(Native Method)

at java.net.PlainSocketImpl.(ServerSocket.java:178)

at org.jivesoftware.wildfire.filetransfer.proxy.ProxyConnectionManager$1.run(Proxy ConnectionManager.java:84)

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)

at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)

at java.util.concurrent.FutureTask.run(FutureTask.java:123)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PubSub maintenance” prio=1 tid=0x084e2568 nid=0x22f9 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d8b418> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d8b418> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Auditor” prio=1 tid=0x0846a990 nid=0x22f7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d722b0> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d722b0> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“MUC cleanup” prio=1 tid=0x084dba50 nid=0x22f6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d65998> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d65998> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Sessions cleanup” prio=1 tid=0x08545730 nid=0x22f5 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9c564f8> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9c564f8> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Thread-2” daemon prio=1 tid=0x08551128 nid=0x22f4 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.database.ConnectionPool.run(ConnectionPool.java:374)

at java.lang.Thread.run(Thread.java:595)

“HSQLDB Timer @1394894” daemon prio=1 tid=0x085a1f00 nid=0x22f0 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)
    at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
  • locked <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)

at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)

at java.lang.Thread.run(Thread.java:595)

“Thread-0” daemon prio=1 tid=0x08127318 nid=0x22ee waiting on condition

at java.lang.Thread.sleep(Native Method)

at com.install4j.runtime.Launcher$StopWatcherThread.run(Unknown Source)

“Low Memory Detector” daemon prio=1 tid=0x080f8fa8 nid=0x22ec runnable

“CompilerThread1” daemon prio=1 tid=0x080f7ad8 nid=0x22eb waiting on condition

“CompilerThread0” daemon prio=1 tid=0x080f6a30 nid=0x22ea waiting on condition

“AdapterThread” daemon prio=1 tid=0x080f58b8 nid=0x22e9 waiting on condition

“Signal Dispatcher” daemon prio=1 tid=0x080f4a20 nid=0x22e8 runnable

“Finalizer” daemon prio=1 tid=0x080e9558 nid=0x22e7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
  • locked <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

“Reference Handler” daemon prio=1 tid=0x080e9010 nid=0x22e6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f98> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:474)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
  • locked <0xa9a58f98> (a java.lang.ref.Reference$Lock)

“main” prio=1 tid=0x0805d568 nid=0x22e3 in Object.wait()

at java.net.Socket.setImpl(Socket.java:431)

at java.net.Socket.(XMPPServer.java:148)

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessor Impl.java:39)

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructor AccessorImpl.java:27)

at java.lang.reflect.Constructor.newInstance(Constructor.java:494)

at java.lang.Class.newInstance0(Class.java:350)

at java.lang.Class.newInstance(Class.java:303)

at org.jivesoftware.wildfire.starter.ServerStarter.start(ServerStarter.java:93)

at org.jivesoftware.wildfire.starter.ServerStarter.main(ServerStarter.java:49)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.ja va:25)

at java.lang.reflect.Method.invoke(Method.java:585)

at com.exe4j.runtime.LauncherEngine.launch(Unknown Source)

at com.install4j.runtime.Launcher.main(Unknown Source)

“VM Thread” prio=1 tid=0x080e6ad0 nid=0x22e5 runnable

“VM Periodic Task Thread” prio=1 tid=0x080fa480 nid=0x22ed waiting on condition

Full thread dump Java HotSpot™ Server VM (1.5.0_08-b03 mixed mode):

“Update Manager” daemon prio=1 tid=0x08478ee0 nid=0x22fb waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.wildfire.update.UpdateManager$1.waitForNextCheck(UpdateManager .java:153)

at org.jivesoftware.wildfire.update.UpdateManager$1.run(UpdateManager.java:106)

“pool-1-thread-1” prio=1 tid=0x084799c8 nid=0x22fa runnable

at java.net.PlainSocketImpl.initProto(Native Method)

at java.net.PlainSocketImpl.(ServerSocket.java:178)

at org.jivesoftware.wildfire.filetransfer.proxy.ProxyConnectionManager$1.run(Proxy ConnectionManager.java:84)

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)

at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)

at java.util.concurrent.FutureTask.run(FutureTask.java:123)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PubSub maintenance” prio=1 tid=0x084e2568 nid=0x22f9 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d8b418> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d8b418> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Auditor” prio=1 tid=0x0846a990 nid=0x22f7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d722b0> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d722b0> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“MUC cleanup” prio=1 tid=0x084dba50 nid=0x22f6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d65998> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d65998> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Sessions cleanup” prio=1 tid=0x08545730 nid=0x22f5 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9c564f8> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9c564f8> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Thread-2” daemon prio=1 tid=0x08551128 nid=0x22f4 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.database.ConnectionPool.run(ConnectionPool.java:374)

at java.lang.Thread.run(Thread.java:595)

“HSQLDB Timer @1394894” daemon prio=1 tid=0x085a1f00 nid=0x22f0 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)
    at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
  • locked <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)

at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)

at java.lang.Thread.run(Thread.java:595)

“Thread-0” daemon prio=1 tid=0x08127318 nid=0x22ee waiting on condition

at java.lang.Thread.sleep(Native Method)

at com.install4j.runtime.Launcher$StopWatcherThread.run(Unknown Source)

“Low Memory Detector” daemon prio=1 tid=0x080f8fa8 nid=0x22ec runnable

“CompilerThread1” daemon prio=1 tid=0x080f7ad8 nid=0x22eb waiting on condition

“CompilerThread0” daemon prio=1 tid=0x080f6a30 nid=0x22ea waiting on condition

“AdapterThread” daemon prio=1 tid=0x080f58b8 nid=0x22e9 waiting on condition

“Signal Dispatcher” daemon prio=1 tid=0x080f4a20 nid=0x22e8 waiting on condition

“Finalizer” daemon prio=1 tid=0x080e9558 nid=0x22e7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
  • locked <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

“Reference Handler” daemon prio=1 tid=0x080e9010 nid=0x22e6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f98> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:474)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
  • locked <0xa9a58f98> (a java.lang.ref.Reference$Lock)

“main” prio=1 tid=0x0805d568 nid=0x22e3 in Object.wait()

at java.net.Socket.setImpl(Socket.java:431)

at java.net.Socket.(XMPPServer.java:148)

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessor Impl.java:39)

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructor AccessorImpl.java:27)

at java.lang.reflect.Constructor.newInstance(Constructor.java:494)

at java.lang.Class.newInstance0(Class.java:350)

at java.lang.Class.newInstance(Class.java:303)

at org.jivesoftware.wildfire.starter.ServerStarter.start(ServerStarter.java:93)

at org.jivesoftware.wildfire.starter.ServerStarter.main(ServerStarter.java:49)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.ja va:25)

at java.lang.reflect.Method.invoke(Method.java:585)

at com.exe4j.runtime.LauncherEngine.launch(Unknown Source)

at com.install4j.runtime.Launcher.main(Unknown Source)

“VM Thread” prio=1 tid=0x080e6ad0 nid=0x22e5 runnable

“VM Periodic Task Thread” prio=1 tid=0x080fa480 nid=0x22ed waiting on condition

Full thread dump Java HotSpot™ Server VM (1.5.0_08-b03 mixed mode):

“Update Manager” daemon prio=1 tid=0x08478ee0 nid=0x22fb waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.wildfire.update.UpdateManager$1.waitForNextCheck(UpdateManager .java:153)

at org.jivesoftware.wildfire.update.UpdateManager$1.run(UpdateManager.java:106)

“pool-1-thread-1” prio=1 tid=0x084799c8 nid=0x22fa runnable

at java.net.PlainSocketImpl.initProto(Native Method)

at java.net.PlainSocketImpl.(ServerSocket.java:178)

at org.jivesoftware.wildfire.filetransfer.proxy.ProxyConnectionManager$1.run(Proxy ConnectionManager.java:84)

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)

at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)

at java.util.concurrent.FutureTask.run(FutureTask.java:123)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PubSub maintenance” prio=1 tid=0x084e2568 nid=0x22f9 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d8b418> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d8b418> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Auditor” prio=1 tid=0x0846a990 nid=0x22f7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d722b0> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d722b0> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“MUC cleanup” prio=1 tid=0x084dba50 nid=0x22f6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d65998> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d65998> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Sessions cleanup” prio=1 tid=0x08545730 nid=0x22f5 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9c564f8> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9c564f8> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Thread-2” daemon prio=1 tid=0x08551128 nid=0x22f4 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.database.ConnectionPool.run(ConnectionPool.java:374)

at java.lang.Thread.run(Thread.java:595)

“HSQLDB Timer @1394894” daemon prio=1 tid=0x085a1f00 nid=0x22f0 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)
    at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
  • locked <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)

at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)

at java.lang.Thread.run(Thread.java:595)

“Thread-0” daemon prio=1 tid=0x08127318 nid=0x22ee waiting on condition

at java.lang.Thread.sleep(Native Method)

at com.install4j.runtime.Launcher$StopWatcherThread.run(Unknown Source)

“Low Memory Detector” daemon prio=1 tid=0x080f8fa8 nid=0x22ec runnable

“CompilerThread1” daemon prio=1 tid=0x080f7ad8 nid=0x22eb waiting on condition

“CompilerThread0” daemon prio=1 tid=0x080f6a30 nid=0x22ea waiting on condition

“AdapterThread” daemon prio=1 tid=0x080f58b8 nid=0x22e9 waiting on condition

“Signal Dispatcher” daemon prio=1 tid=0x080f4a20 nid=0x22e8 waiting on condition

“Finalizer” daemon prio=1 tid=0x080e9558 nid=0x22e7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
  • locked <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

“Reference Handler” daemon prio=1 tid=0x080e9010 nid=0x22e6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f98> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:474)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
  • locked <0xa9a58f98> (a java.lang.ref.Reference$Lock)

“main” prio=1 tid=0x0805d568 nid=0x22e3 in Object.wait()

at java.net.Socket.setImpl(Socket.java:431)

at java.net.Socket.(XMPPServer.java:148)

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessor Impl.java:39)

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructor AccessorImpl.java:27)

at java.lang.reflect.Constructor.newInstance(Constructor.java:494)

at java.lang.Class.newInstance0(Class.java:350)

at java.lang.Class.newInstance(Class.java:303)

at org.jivesoftware.wildfire.starter.ServerStarter.start(ServerStarter.java:93)

at org.jivesoftware.wildfire.starter.ServerStarter.main(ServerStarter.java:49)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.ja va:25)

at java.lang.reflect.Method.invoke(Method.java:585)

at com.exe4j.runtime.LauncherEngine.launch(Unknown Source)

at com.install4j.runtime.Launcher.main(Unknown Source)

“VM Thread” prio=1 tid=0x080e6ad0 nid=0x22e5 runnable

“VM Periodic Task Thread” prio=1 tid=0x080fa480 nid=0x22ed waiting on condition

Full thread dump Java HotSpot™ Server VM (1.5.0_08-b03 mixed mode):

“Update Manager” daemon prio=1 tid=0x08478ee0 nid=0x22fb waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.wildfire.update.UpdateManager$1.waitForNextCheck(UpdateManager .java:153)

at org.jivesoftware.wildfire.update.UpdateManager$1.run(UpdateManager.java:106)

“pool-1-thread-1” prio=1 tid=0x084799c8 nid=0x22fa runnable

at java.net.PlainSocketImpl.initProto(Native Method)

at java.net.PlainSocketImpl.(ServerSocket.java:178)

at org.jivesoftware.wildfire.filetransfer.proxy.ProxyConnectionManager$1.run(Proxy ConnectionManager.java:84)

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)

at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)

at java.util.concurrent.FutureTask.run(FutureTask.java:123)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PubSub maintenance” prio=1 tid=0x084e2568 nid=0x22f9 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d8b418> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d8b418> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Auditor” prio=1 tid=0x0846a990 nid=0x22f7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d722b0> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d722b0> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“MUC cleanup” prio=1 tid=0x084dba50 nid=0x22f6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d65998> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d65998> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Sessions cleanup” prio=1 tid=0x08545730 nid=0x22f5 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9c564f8> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9c564f8> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Thread-2” daemon prio=1 tid=0x08551128 nid=0x22f4 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.database.ConnectionPool.run(ConnectionPool.java:374)

at java.lang.Thread.run(Thread.java:595)

“HSQLDB Timer @1394894” daemon prio=1 tid=0x085a1f00 nid=0x22f0 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)
    at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
  • locked <0xa9aebe70> (a org.hsqldb.lib.HsqlTimer)

at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)

at java.lang.Thread.run(Thread.java:595)

“Thread-0” daemon prio=1 tid=0x08127318 nid=0x22ee waiting on condition

at java.lang.Thread.sleep(Native Method)

at com.install4j.runtime.Launcher$StopWatcherThread.run(Unknown Source)

“Low Memory Detector” daemon prio=1 tid=0x080f8fa8 nid=0x22ec runnable

“CompilerThread1” daemon prio=1 tid=0x080f7ad8 nid=0x22eb waiting on condition

“CompilerThread0” daemon prio=1 tid=0x080f6a30 nid=0x22ea waiting on condition

“AdapterThread” daemon prio=1 tid=0x080f58b8 nid=0x22e9 waiting on condition

“Signal Dispatcher” daemon prio=1 tid=0x080f4a20 nid=0x22e8 waiting on condition

“Finalizer” daemon prio=1 tid=0x080e9558 nid=0x22e7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
  • locked <0xa9a58f18> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

“Reference Handler” daemon prio=1 tid=0x080e9010 nid=0x22e6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a58f98> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:474)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
  • locked <0xa9a58f98> (a java.lang.ref.Reference$Lock)

“main” prio=1 tid=0x0805d568 nid=0x22e3 in Object.wait()

at java.net.Socket.setImpl(Socket.java:431)

at java.net.Socket.(XMPPServer.java:148)

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessor Impl.java:39)

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructor AccessorImpl.java:27)

at java.lang.reflect.Constructor.newInstance(Constructor.java:494)

at java.lang.Class.newInstance0(Class.java:350)

at java.lang.Class.newInstance(Class.java:303)

at org.jivesoftware.wildfire.starter.ServerStarter.start(ServerStarter.java:93)

at org.jivesoftware.wildfire.starter.ServerStarter.main(ServerStarter.java:49)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.ja va:25)

at java.lang.reflect.Method.invoke(Method.java:585)

at com.exe4j.runtime.LauncherEngine.launch(Unknown Source)

at com.install4j.runtime.Launcher.main(Unknown Source)

“VM Thread” prio=1 tid=0x080e6ad0 nid=0x22e5 runnable

“VM Periodic Task Thread” prio=1 tid=0x080fa480 nid=0x22ed waiting on condition

Full thread dump Java HotSpot™ Server VM (1.5.0_08-b03 mixed mode):

“btpool0-2 - Acceptor0 AdminConsolePlugin$JiveSslConnector @ 0.0.0.0:9091” prio=1 tid=0x0867b510 nid=0x230a runnable

at java.net.PlainSocketImpl.socketAccept(Native Method)

at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)

  • locked <0xa9f10958> (a java.net.SocksSocketImpl)

at java.net.ServerSocket.implAccept(ServerSocket.java:450)

at com.sun.net.ssl.internal.ssl.SSLServerSocketImpl.accept(SSLServerSocketImpl.jav a:259)

at org.mortbay.jetty.bio.SocketConnector.accept(SocketConnector.java:97)

at org.mortbay.jetty.AbstractConnector$Acceptor.run(AbstractConnector.java:498)

at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)

“btpool0-1 - Acceptor0 SelectChannelConnector @ 0.0.0.0:9090” prio=1 tid=0x0861a5d8 nid=0x2309 runnable

at sun.nio.ch.PollArrayWrapper.poll0(Native Method)

at sun.nio.ch.PollArrayWrapper.poll(PollArrayWrapper.java:100)

at sun.nio.ch.PollSelectorImpl.doSelect(PollSelectorImpl.java:56)

at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)

  • locked <0xa9f07230> (a sun.nio.ch.Util$1)
  • locked <0xa9f07240> (a java.util.Collections$UnmodifiableSet)
  • locked <0xa9f071e0> (a sun.nio.ch.PollSelectorImpl)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)

at org.mortbay.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java:299)

at org.mortbay.io.nio.SelectorManager.doSelect(SelectorManager.java:73)

at org.mortbay.jetty.nio.SelectChannelConnector.accept(SelectChannelConnector.java :120)

at org.mortbay.jetty.AbstractConnector$Acceptor.run(AbstractConnector.java:498)

at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)

"btpool0-0 - Invalidator - " prio=1 tid=0x082fc088 nid=0x2308 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.mortbay.jetty.servlet.HashSessionManager$SessionScavenger.run(HashSessionMa nager.java:289)

at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)

“DestroyJavaVM” prio=1 tid=0x0805d568 nid=0x22e3 waiting on condition

“pool-9-thread-1” prio=1 tid=0x085b9e48 nid=0x2307 waiting on condition

at sun.misc.Unsafe.park(Native Method)

at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:146)

at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNano s(AbstractQueuedSynchronizer.java:1803)

at java.util.concurrent.DelayQueue.take(DelayQueue.java:135)

at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(Schedule dThreadPoolExecutor.java:504)

at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(Schedule dThreadPoolExecutor.java:497)

at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:470)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:674)

at java.lang.Thread.run(Thread.java:595)

“SocketSendingTracker” daemon prio=1 tid=0x083023c0 nid=0x2306 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9e621d0> (a org.jivesoftware.wildfire.net.SocketSendingTracker$1)
    at org.jivesoftware.wildfire.net.SocketSendingTracker$1.run(SocketSendingTracker.j ava:64)
  • locked <0xa9e621d0> (a org.jivesoftware.wildfire.net.SocketSendingTracker$1)

“SocketAcceptor-1” prio=1 tid=0x0818cc38 nid=0x2305 runnable

at sun.nio.ch.PollArrayWrapper.poll0(Native Method)

at sun.nio.ch.PollArrayWrapper.poll(PollArrayWrapper.java:100)

at sun.nio.ch.PollSelectorImpl.doSelect(PollSelectorImpl.java:56)

at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)

  • locked <0xa9e50da0> (a sun.nio.ch.Util$1)
  • locked <0xa9e50db0> (a java.util.Collections$UnmodifiableSet)
  • locked <0xa9e50d50> (a sun.nio.ch.PollSelectorImpl)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)

at org.apache.mina.transport.socket.nio.SocketAcceptor$Worker.run(SocketAcceptor.j ava:230)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:43)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“SocketAcceptor-0” prio=1 tid=0x08315b50 nid=0x2304 runnable

at sun.nio.ch.PollArrayWrapper.poll0(Native Method)

at sun.nio.ch.PollArrayWrapper.poll(PollArrayWrapper.java:100)

at sun.nio.ch.PollSelectorImpl.doSelect(PollSelectorImpl.java:56)

at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)

  • locked <0xa9d46518> (a sun.nio.ch.Util$1)
  • locked <0xa9d46508> (a java.util.Collections$UnmodifiableSet)
  • locked <0xa9d45360> (a sun.nio.ch.PollSelectorImpl)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)

at org.apache.mina.transport.socket.nio.SocketAcceptor$Worker.run(SocketAcceptor.j ava:230)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:43)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PooledByteBufferExpirer-0” daemon prio=1 tid=0x085b4590 nid=0x2303 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.apache.mina.common.PooledByteBufferAllocator$Expirer.run(PooledByteBufferAl locator.java:277)

“Update Manager” daemon prio=1 tid=0x08478ee0 nid=0x22fb waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.wildfire.update.UpdateManager$1.waitForNextCheck(UpdateManager .java:153)

at org.jivesoftware.wildfire.update.UpdateManager$1.run(UpdateManager.java:106)

“pool-1-thread-1” prio=1 tid=0x084799c8 nid=0x22fa runnable

at java.net.PlainSocketImpl.socketAccept(Native Method)

at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)

  • locked <0xa9d430c8> (a java.net.SocksSocketImpl)

at java.net.ServerSocket.implAccept(ServerSocket.java:450)

at java.net.ServerSocket.accept(ServerSocket.java:421)

at org.jivesoftware.wildfire.filetransfer.proxy.ProxyConnectionManager$1.run(Proxy ConnectionManager.java:93)

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)

at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)

at java.util.concurrent.FutureTask.run(FutureTask.java:123)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PubSub maintenance” prio=1 tid=0x084e2568 nid=0x22f9 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d2b418> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d2b418> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Auditor” prio=1 tid=0x0846a990 nid=0x22f7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d12450> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d12450> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“MUC cleanup” prio=1 tid=0x084dba50 nid=0x22f6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d06880> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d06880> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Sessions cleanup” prio=1 tid=0x08545730 nid=0x22f5 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9c21918> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9c21918> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Thread-2” daemon prio=1 tid=0x08551128 nid=0x22f4 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.database.ConnectionPool.run(ConnectionPool.java:374)

at java.lang.Thread.run(Thread.java:595)

“HSQLDB Timer @1394894” daemon prio=1 tid=0x085a1f00 nid=0x22f0 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9ac6f00> (a org.hsqldb.lib.HsqlTimer)
    at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
  • locked <0xa9ac6f00> (a org.hsqldb.lib.HsqlTimer)

at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)

at java.lang.Thread.run(Thread.java:595)

“Thread-0” daemon prio=1 tid=0x08127318 nid=0x22ee waiting on condition

at java.lang.Thread.sleep(Native Method)

at com.install4j.runtime.Launcher$StopWatcherThread.run(Unknown Source)

“Low Memory Detector” daemon prio=1 tid=0x080f8fa8 nid=0x22ec runnable

“CompilerThread1” daemon prio=1 tid=0x080f7ad8 nid=0x22eb waiting on condition

“CompilerThread0” daemon prio=1 tid=0x080f6a30 nid=0x22ea waiting on condition

“AdapterThread” daemon prio=1 tid=0x080f58b8 nid=0x22e9 waiting on condition

“Signal Dispatcher” daemon prio=1 tid=0x080f4a20 nid=0x22e8 waiting on condition

“Finalizer” daemon prio=1 tid=0x080e9558 nid=0x22e7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a50298> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
  • locked <0xa9a50298> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

“Reference Handler” daemon prio=1 tid=0x080e9010 nid=0x22e6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a50318> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:474)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
  • locked <0xa9a50318> (a java.lang.ref.Reference$Lock)

“VM Thread” prio=1 tid=0x080e6ad0 nid=0x22e5 runnable

“VM Periodic Task Thread” prio=1 tid=0x080fa480 nid=0x22ed waiting on condition

Full thread dump Java HotSpot™ Server VM (1.5.0_08-b03 mixed mode):

“btpool0-2 - Acceptor0 AdminConsolePlugin$JiveSslConnector @ 0.0.0.0:9091” prio=1 tid=0x0867b510 nid=0x230a runnable

at java.net.PlainSocketImpl.socketAccept(Native Method)

at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)

  • locked <0xa9f10958> (a java.net.SocksSocketImpl)

at java.net.ServerSocket.implAccept(ServerSocket.java:450)

at com.sun.net.ssl.internal.ssl.SSLServerSocketImpl.accept(SSLServerSocketImpl.jav a:259)

at org.mortbay.jetty.bio.SocketConnector.accept(SocketConnector.java:97)

at org.mortbay.jetty.AbstractConnector$Acceptor.run(AbstractConnector.java:498)

at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)

“btpool0-1 - Acceptor0 SelectChannelConnector @ 0.0.0.0:9090” prio=1 tid=0x0861a5d8 nid=0x2309 runnable

at sun.nio.ch.PollArrayWrapper.poll0(Native Method)

at sun.nio.ch.PollArrayWrapper.poll(PollArrayWrapper.java:100)

at sun.nio.ch.PollSelectorImpl.doSelect(PollSelectorImpl.java:56)

at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)

  • locked <0xa9f07230> (a sun.nio.ch.Util$1)
  • locked <0xa9f07240> (a java.util.Collections$UnmodifiableSet)
  • locked <0xa9f071e0> (a sun.nio.ch.PollSelectorImpl)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)

at org.mortbay.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java:299)

at org.mortbay.io.nio.SelectorManager.doSelect(SelectorManager.java:73)

at org.mortbay.jetty.nio.SelectChannelConnector.accept(SelectChannelConnector.java :120)

at org.mortbay.jetty.AbstractConnector$Acceptor.run(AbstractConnector.java:498)

at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)

"btpool0-0 - Invalidator - " prio=1 tid=0x082fc088 nid=0x2308 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.mortbay.jetty.servlet.HashSessionManager$SessionScavenger.run(HashSessionMa nager.java:289)

at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)

“DestroyJavaVM” prio=1 tid=0x0805d568 nid=0x22e3 waiting on condition

“pool-9-thread-1” prio=1 tid=0x085b9e48 nid=0x2307 waiting on condition

at sun.misc.Unsafe.park(Native Method)

at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:146)

at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNano s(AbstractQueuedSynchronizer.java:1803)

at java.util.concurrent.DelayQueue.take(DelayQueue.java:135)

at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(Schedule dThreadPoolExecutor.java:504)

at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(Schedule dThreadPoolExecutor.java:497)

at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:470)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:674)

at java.lang.Thread.run(Thread.java:595)

“SocketSendingTracker” daemon prio=1 tid=0x083023c0 nid=0x2306 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9e621d0> (a org.jivesoftware.wildfire.net.SocketSendingTracker$1)
    at org.jivesoftware.wildfire.net.SocketSendingTracker$1.run(SocketSendingTracker.j ava:64)
  • locked <0xa9e621d0> (a org.jivesoftware.wildfire.net.SocketSendingTracker$1)

“SocketAcceptor-1” prio=1 tid=0x0818cc38 nid=0x2305 runnable

at sun.nio.ch.PollArrayWrapper.poll0(Native Method)

at sun.nio.ch.PollArrayWrapper.poll(PollArrayWrapper.java:100)

at sun.nio.ch.PollSelectorImpl.doSelect(PollSelectorImpl.java:56)

at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)

  • locked <0xa9e50da0> (a sun.nio.ch.Util$1)
  • locked <0xa9e50db0> (a java.util.Collections$UnmodifiableSet)
  • locked <0xa9e50d50> (a sun.nio.ch.PollSelectorImpl)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)

at org.apache.mina.transport.socket.nio.SocketAcceptor$Worker.run(SocketAcceptor.j ava:230)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:43)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“SocketAcceptor-0” prio=1 tid=0x08315b50 nid=0x2304 runnable

at sun.nio.ch.PollArrayWrapper.poll0(Native Method)

at sun.nio.ch.PollArrayWrapper.poll(PollArrayWrapper.java:100)

at sun.nio.ch.PollSelectorImpl.doSelect(PollSelectorImpl.java:56)

at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)

  • locked <0xa9d46518> (a sun.nio.ch.Util$1)
  • locked <0xa9d46508> (a java.util.Collections$UnmodifiableSet)
  • locked <0xa9d45360> (a sun.nio.ch.PollSelectorImpl)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)

at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)

at org.apache.mina.transport.socket.nio.SocketAcceptor$Worker.run(SocketAcceptor.j ava:230)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:43)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PooledByteBufferExpirer-0” daemon prio=1 tid=0x085b4590 nid=0x2303 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.apache.mina.common.PooledByteBufferAllocator$Expirer.run(PooledByteBufferAl locator.java:277)

“Update Manager” daemon prio=1 tid=0x08478ee0 nid=0x22fb waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.wildfire.update.UpdateManager$1.waitForNextCheck(UpdateManager .java:153)

at org.jivesoftware.wildfire.update.UpdateManager$1.run(UpdateManager.java:106)

“pool-1-thread-1” prio=1 tid=0x084799c8 nid=0x22fa runnable

at java.net.PlainSocketImpl.socketAccept(Native Method)

at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)

  • locked <0xa9d430c8> (a java.net.SocksSocketImpl)

at java.net.ServerSocket.implAccept(ServerSocket.java:450)

at java.net.ServerSocket.accept(ServerSocket.java:421)

at org.jivesoftware.wildfire.filetransfer.proxy.ProxyConnectionManager$1.run(Proxy ConnectionManager.java:93)

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)

at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)

at java.util.concurrent.FutureTask.run(FutureTask.java:123)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java: 650)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)

at java.lang.Thread.run(Thread.java:595)

“PubSub maintenance” prio=1 tid=0x084e2568 nid=0x22f9 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d2b418> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d2b418> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Auditor” prio=1 tid=0x0846a990 nid=0x22f7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d12450> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d12450> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“MUC cleanup” prio=1 tid=0x084dba50 nid=0x22f6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9d06880> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9d06880> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Sessions cleanup” prio=1 tid=0x08545730 nid=0x22f5 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9c21918> (a java.util.TaskQueue)
    at java.util.TimerThread.mainLoop(Timer.java:509)
  • locked <0xa9c21918> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:462)

“Thread-2” daemon prio=1 tid=0x08551128 nid=0x22f4 waiting on condition

at java.lang.Thread.sleep(Native Method)

at org.jivesoftware.database.ConnectionPool.run(ConnectionPool.java:374)

at java.lang.Thread.run(Thread.java:595)

“HSQLDB Timer @1394894” daemon prio=1 tid=0x085a1f00 nid=0x22f0 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9ac6f00> (a org.hsqldb.lib.HsqlTimer)
    at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source)
  • locked <0xa9ac6f00> (a org.hsqldb.lib.HsqlTimer)

at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source)

at java.lang.Thread.run(Thread.java:595)

“Thread-0” daemon prio=1 tid=0x08127318 nid=0x22ee waiting on condition

at java.lang.Thread.sleep(Native Method)

at com.install4j.runtime.Launcher$StopWatcherThread.run(Unknown Source)

“Low Memory Detector” daemon prio=1 tid=0x080f8fa8 nid=0x22ec runnable

“CompilerThread1” daemon prio=1 tid=0x080f7ad8 nid=0x22eb waiting on condition

“CompilerThread0” daemon prio=1 tid=0x080f6a30 nid=0x22ea waiting on condition

“AdapterThread” daemon prio=1 tid=0x080f58b8 nid=0x22e9 waiting on condition

“Signal Dispatcher” daemon prio=1 tid=0x080f4a20 nid=0x22e8 waiting on condition

“Finalizer” daemon prio=1 tid=0x080e9558 nid=0x22e7 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a50298> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
  • locked <0xa9a50298> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

“Reference Handler” daemon prio=1 tid=0x080e9010 nid=0x22e6 in Object.wait()

at java.lang.Object.wait(Native Method)

  • waiting on <0xa9a50318> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:474)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
  • locked <0xa9a50318> (a java.lang.ref.Reference$Lock)

“VM Thread” prio=1 tid=0x080e6ad0 nid=0x22e5 runnable

“VM Periodic Task Thread” prio=1 tid=0x080fa480 nid=0x22ed waiting on condition

– end nohup.out –

8

Hi,

this thread seems to be the one which “hangs” but I have yet no idea why. It’‘s NIO code, so maybe there’'s a problem within Java 1.5.0_08.

LG

"main" prio=1 tid=0x0805d568 nid=0x22e3 in Object.wait() http://0xbfa82000..0xbfa83548
at java.net.Socket.setImpl(Socket.java:431)
at java.net.Socket.<init>(Socket.java:68)
at org.apache.mina.transport.socket.nio.SocketSessionConfigImpl.initialize(SocketSessionConfigImpl.java:61)
at org.apache.mina.transport.socket.nio.SocketSessionConfigImpl.<clinit>(SocketSessionConfigImpl.java:54)
at org.apache.mina.transport.socket.nio.SocketAcceptorConfig.<init>(SocketAcceptorConfig.java:38)
at org.apache.mina.transport.socket.nio.SocketAcceptor.<init>(SocketAcceptor.java:63)
at org.jivesoftware.wildfire.spi.ConnectionManagerImpl.buildSocketAcceptor(ConnectionManagerImpl.java:670)
at org.jivesoftware.wildfire.spi.ConnectionManagerImpl.startClientListeners(ConnectionManagerImpl.java:235)
at org.jivesoftware.wildfire.spi.ConnectionManagerImpl.createSocket(ConnectionManagerImpl.java:102)
at org.jivesoftware.wildfire.spi.ConnectionManagerImpl.start(ConnectionManagerImpl.java:705)
at org.jivesoftware.wildfire.XMPPServer.startModules(XMPPServer.java:532)
at org.jivesoftware.wildfire.XMPPServer.start(XMPPServer.java:400)
at org.jivesoftware.wildfire.XMPPServer.<init>(XMPPServer.java:148)
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
at java.lang.reflect.Constructor.newInstance(Constructor.java:494)
at java.lang.Class.newInstance0(Class.java:350)
at java.lang.Class.newInstance(Class.java:303)
at org.jivesoftware.wildfire.starter.ServerStarter.start(ServerStarter.java:93)
at org.jivesoftware.wildfire.starter.ServerStarter.main(ServerStarter.java:49)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at com.exe4j.runtime.LauncherEngine.launch(Unknown Source)
at com.install4j.runtime.Launcher.main(Unknown Source)