UCM 11g Managed Server Start Up Takes Over 5 Minutes and Hangs at java/security/SecureRandom.generateSeed
(Doc ID 1368532.1)
Last updated on MAY 01, 2023
Applies to:
Oracle WebCenter Content - Version 11.1.1.3.0 to 11.1.1.5.0 [Release 11g]Information in this document applies to any platform.
Symptoms
Starting up the UCM Managed Server takes over 5 minutes. The UCM server logs show a 5 minute delay between the following lines:
####<Oct 5, 2011 1:35:14 PM CEST> <Info> <IIOP> <ath-bv-ucm-klif> <AdminServer> <[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'> <> <> <> <1317814514503> <BEA-002014> <IIOP subsystem enabled.>
####<Oct 5, 2011 1:35:18 PM CEST> <Info> <Security> <ath-bv-ucm-klif> <AdminServer> <[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'> <> <> <> <1317814518791> <BEA-090894> <Successfully loaded the OPSS Policy Provider using oracle.security.jps.internal.policystore.JavaPolicyProvider.>
####<Oct 5, 2011 1:35:18 PM CEST> <Info> <Security> <ath-bv-ucm-klif> <AdminServer> <[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'> <> <> <> <1317814518791> <BEA-090894> <Successfully loaded the OPSS Policy Provider using oracle.security.jps.internal.policystore.JavaPolicyProvider.>
A java thread dump (taken using a "kill -3 <pid>" on the java process) shows the following thread stack:
"Main Thread" id=1 idx=0x4 tid=29201 prio=5 alive, waiting, native_blocked
-- Waiting for notification on: weblogic/t3/srvr/SubsystemRequest@0xe8e58b10[fat lock]
at jrockit/vm/Threads.waitForNotifySignal(JLjava/lang/Object;)Z(Native Method)
at java/lang/Object.wait(J)V(Native Method)
at weblogic/t3/srvr/SubsystemRequest.rendezvouz(SubsystemRequest.java:100)
^-- Lock released while waiting: weblogic/t3/srvr/SubsystemRequest@0xe8e58b10[fat lock]
at weblogic/t3/srvr/SubsystemRequest.action(SubsystemRequest.java:81)
at weblogic/t3/srvr/SubsystemRequest.start(SubsystemRequest.java:51)
at weblogic/t3/srvr/ServerServicesManager.startService(ServerServicesManager.java:459)
at weblogic/t3/srvr/ServerServicesManager.startInStandbyState(ServerServicesManager.java:166)
^-- Holding lock: java/lang/Class@0xe8e54168[biased lock]
at weblogic/t3/srvr/T3Srvr.initializeStandby(T3Srvr.java:879)
at weblogic/t3/srvr/T3Srvr.startup(T3Srvr.java:566)
at weblogic/t3/srvr/T3Srvr.run(T3Srvr.java:467)
at weblogic/Server.main(Server.java:71)
at jrockit/vm/RNI.c2java(JJJJJ)V(Native Method)
-- end of trace
..
"[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'" id=15 idx=0x34 tid=29232 prio=5 alive, in native, daemon
at jrockit/io/FileNativeIO.readBytesPinned(Ljava/io/FileDescriptor;[BII)I(Native Method)
at jrockit/io/FileNativeIO.readBytes(FileNativeIO.java:32)
at java/io/FileInputStream.readBytes([BII)I(FileInputStream.java)
at java/io/FileInputStream.read(FileInputStream.java:199)
at sun/security/provider/NativePRNG$RandomIO.readFully(NativePRNG.java:185)
at sun/security/provider/NativePRNG$RandomIO.implGenerateSeed(NativePRNG.java:202)
^-- Holding lock: java/lang/Object@0xe97b7ac0[biased lock]
at sun/security/provider/NativePRNG$RandomIO.access$300(NativePRNG.java:108)
at sun/security/provider/NativePRNG.engineGenerateSeed(NativePRNG.java:102)
at java/security/SecureRandom.generateSeed(SecureRandom.java:495)
at com/bea/security/utils/random/AbstractRandomData.ensureInittedAndSeeded(AbstractRandomData.java:91)
^-- Holding lock: com/bea/security/utils/random/SecureRandomData@0xe126eb30[recursive]
at com/bea/security/utils/random/AbstractRandomData.getRandomBytes(AbstractRandomData.java:105)
^-- Holding lock: com/bea/security/utils/random/SecureRandomData@0xe126eb30[biased lock]
at com/bea/security/utils/random/AbstractRandomData.getRandomBytes(AbstractRandomData.java:100)
at weblogic/management/servlet/ConnectionSigner.signConnection(ConnectionSigner.java:132)
^-- Holding lock: java/lang/Class@0xe9894ed8[biased lock]
at weblogic/ldap/EmbeddedLDAP.getInitialReplicaFromAdminServer(EmbeddedLDAP.java:1332)
at weblogic/ldap/EmbeddedLDAP.start(EmbeddedLDAP.java:251)
at weblogic/t3/srvr/SubsystemRequest.run(SubsystemRequest.java:64)
at weblogic/work/ExecuteThread.execute(ExecuteThread.java:207)
at weblogic/work/ExecuteThread.run(ExecuteThread.java:176)
at jrockit/vm/RNI.c2java(JJJJJ)V(Native Method)
-- end of trace
-- Waiting for notification on: weblogic/t3/srvr/SubsystemRequest@0xe8e58b10[fat lock]
at jrockit/vm/Threads.waitForNotifySignal(JLjava/lang/Object;)Z(Native Method)
at java/lang/Object.wait(J)V(Native Method)
at weblogic/t3/srvr/SubsystemRequest.rendezvouz(SubsystemRequest.java:100)
^-- Lock released while waiting: weblogic/t3/srvr/SubsystemRequest@0xe8e58b10[fat lock]
at weblogic/t3/srvr/SubsystemRequest.action(SubsystemRequest.java:81)
at weblogic/t3/srvr/SubsystemRequest.start(SubsystemRequest.java:51)
at weblogic/t3/srvr/ServerServicesManager.startService(ServerServicesManager.java:459)
at weblogic/t3/srvr/ServerServicesManager.startInStandbyState(ServerServicesManager.java:166)
^-- Holding lock: java/lang/Class@0xe8e54168[biased lock]
at weblogic/t3/srvr/T3Srvr.initializeStandby(T3Srvr.java:879)
at weblogic/t3/srvr/T3Srvr.startup(T3Srvr.java:566)
at weblogic/t3/srvr/T3Srvr.run(T3Srvr.java:467)
at weblogic/Server.main(Server.java:71)
at jrockit/vm/RNI.c2java(JJJJJ)V(Native Method)
-- end of trace
..
"[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'" id=15 idx=0x34 tid=29232 prio=5 alive, in native, daemon
at jrockit/io/FileNativeIO.readBytesPinned(Ljava/io/FileDescriptor;[BII)I(Native Method)
at jrockit/io/FileNativeIO.readBytes(FileNativeIO.java:32)
at java/io/FileInputStream.readBytes([BII)I(FileInputStream.java)
at java/io/FileInputStream.read(FileInputStream.java:199)
at sun/security/provider/NativePRNG$RandomIO.readFully(NativePRNG.java:185)
at sun/security/provider/NativePRNG$RandomIO.implGenerateSeed(NativePRNG.java:202)
^-- Holding lock: java/lang/Object@0xe97b7ac0[biased lock]
at sun/security/provider/NativePRNG$RandomIO.access$300(NativePRNG.java:108)
at sun/security/provider/NativePRNG.engineGenerateSeed(NativePRNG.java:102)
at java/security/SecureRandom.generateSeed(SecureRandom.java:495)
at com/bea/security/utils/random/AbstractRandomData.ensureInittedAndSeeded(AbstractRandomData.java:91)
^-- Holding lock: com/bea/security/utils/random/SecureRandomData@0xe126eb30[recursive]
at com/bea/security/utils/random/AbstractRandomData.getRandomBytes(AbstractRandomData.java:105)
^-- Holding lock: com/bea/security/utils/random/SecureRandomData@0xe126eb30[biased lock]
at com/bea/security/utils/random/AbstractRandomData.getRandomBytes(AbstractRandomData.java:100)
at weblogic/management/servlet/ConnectionSigner.signConnection(ConnectionSigner.java:132)
^-- Holding lock: java/lang/Class@0xe9894ed8[biased lock]
at weblogic/ldap/EmbeddedLDAP.getInitialReplicaFromAdminServer(EmbeddedLDAP.java:1332)
at weblogic/ldap/EmbeddedLDAP.start(EmbeddedLDAP.java:251)
at weblogic/t3/srvr/SubsystemRequest.run(SubsystemRequest.java:64)
at weblogic/work/ExecuteThread.execute(ExecuteThread.java:207)
at weblogic/work/ExecuteThread.run(ExecuteThread.java:176)
at jrockit/vm/RNI.c2java(JJJJJ)V(Native Method)
-- end of trace
Note that the "hang" occurs when native code is trying to seed the random number generator.
Changes
Cause
To view full details, sign in with your My Oracle Support account. |
|
Don't have a My Oracle Support account? Click to get started! |
In this Document
| Symptoms |
| Changes |
| Cause |
| Solution |
| References |