Uploaded image for project: 'OpenAM'
  1. OpenAM
  2. OPENAM-17215

Policy debug log fills up at very high pace if the config store is not found

    Details

    • Type: Bug
    • Status: Open
    • Priority: Minor
    • Resolution: Unresolved
    • Affects Version/s: 7.0.1
    • Fix Version/s: None
    • Component/s: debug logging
    • Labels:
      None
    • Environment:
      AM 7.0.1 out of the box
    • Rank:
      1|i0355z:

      Description

      Bug description

      If the config store is not found the debug Policy log fills up with messages starting with:

      o.f.o.e.i.IndexChangeManagerImpl: 2020-12-17 12:05:44,356: Thread[pool-3-thread-1]: TransactionId[]
      ERROR: Error attempting to initiate index change monitor.
      org.forgerock.openam.entitlement.indextree.ChangeMonitorException: Failed creating persistent search.
      [CONTINUED]     at org.forgerock.openam.entitlement.indextree.IndexChangeMonitorImpl.start(IndexChangeMonitorImpl.java:89)
      [CONTINUED]     at org.forgerock.openam.entitlement.indextree.IndexChangeManagerImpl$MonitorTask.run(IndexChangeManagerImpl.java:151)
      [CONTINUED]     at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
      [CONTINUED]     at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
      [CONTINUED]     at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
      [CONTINUED]     at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
      [CONTINUED]     at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
      [CONTINUED]     at java.base/java.lang.Thread.run(Thread.java:834)
      [CONTINUED]Caused by: org.forgerock.opendj.ldap.ConnectionException: Connect Error: No operational connection factories available
      [CONTINUED]     at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:230)
      [CONTINUED]     at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:145)
      [CONTINUED]     at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:114)
      [CONTINUED]     at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:72)
      [CONTINUED]     at org.forgerock.opendj.ldap.LoadBalancer.noOperationalConnectionFactoriesException(LoadBalancer.java:761)
      [CONTINUED]     at org.forgerock.opendj.ldap.LoadBalancer.connect0(LoadBalancer.java:322)
      [CONTINUED]     at io.reactivex.internal.operators.single.SingleFromCallable.subscribeActual(SingleFromCallable.java:44)
      [CONTINUED]     at io.reactivex.Single.subscribe(Single.java:3666)
      [CONTINUED]     at io.reactivex.internal.operators.single.SingleMap.subscribeActual(SingleMap.java:34)
      [CONTINUED]     at io.reactivex.Single.subscribe(Single.java:3666)
      [CONTINUED]     at io.reactivex.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
      [CONTINUED]     at io.reactivex.Single.subscribe(Single.java:3666)
      [CONTINUED]     at io.reactivex.internal.operators.single.SingleDoFinally.subscribeActual(SingleDoFinally.java:44)
      [CONTINUED]     at io.reactivex.Single.subscribe(Single.java:3666)
      [CONTINUED]     at io.reactivex.Single.subscribe(Single.java:3652)
      [CONTINUED]     at org.forgerock.opendj.ldap.LdapConnectionFactory.getConnectionAsync(LdapConnectionFactory.java:357)
      [CONTINUED]     at org.forgerock.opendj.ldap.LdapConnectionFactory.getConnection(LdapConnectionFactory.java:364)
      [CONTINUED]     at org.forgerock.openam.service.datastore.LdapDataStoreService$ManagedConnectionFactory.getConnection(LdapDataStoreService.java:337)
      [CONTINUED]     at org.forgerock.openam.entitlement.indextree.IndexChangeMonitorImpl.start(IndexChangeMonitorImpl.java:78)
      [CONTINUED]     ... 7 common frames omitted
      [CONTINUED]org.forgerock.openam.entitlement.indextree.ChangeMonitorException: Failed creating persistent search.
              at org.forgerock.openam.entitlement.indextree.IndexChangeMonitorImpl.start(IndexChangeMonitorImpl.java:89)
              at org.forgerock.openam.entitlement.indextree.IndexChangeManagerImpl$MonitorTask.run(IndexChangeManagerImpl.java:151)
              at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
              at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
              at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
              at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
              at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
              at java.base/java.lang.Thread.run(Thread.java:834)
      Caused by: org.forgerock.opendj.ldap.ConnectionException: Connect Error: No operational connection factories available
              at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:230)
              at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:145)
              at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:114)
              at org.forgerock.opendj.ldap.LdapException.newLdapException(LdapException.java:72)
              at org.forgerock.opendj.ldap.LoadBalancer.noOperationalConnectionFactoriesException(LoadBalancer.java:761)
              at org.forgerock.opendj.ldap.LoadBalancer.connect0(LoadBalancer.java:322)
              at io.reactivex.internal.operators.single.SingleFromCallable.subscribeActual(SingleFromCallable.java:44)
              at io.reactivex.Single.subscribe(Single.java:3666)
              at io.reactivex.internal.operators.single.SingleMap.subscribeActual(SingleMap.java:34)
              at io.reactivex.Single.subscribe(Single.java:3666)
              at io.reactivex.internal.operators.single.SingleObserveOn.subscribeActual(SingleObserveOn.java:35)
              at io.reactivex.Single.subscribe(Single.java:3666)
              at io.reactivex.internal.operators.single.SingleDoFinally.subscribeActual(SingleDoFinally.java:44)
              at io.reactivex.Single.subscribe(Single.java:3666)
              at io.reactivex.Single.subscribe(Single.java:3652)
              at org.forgerock.opendj.ldap.LdapConnectionFactory.getConnectionAsync(LdapConnectionFactory.java:357)
              at org.forgerock.opendj.ldap.LdapConnectionFactory.getConnection(LdapConnectionFactory.java:364)
              at org.forgerock.openam.service.datastore.LdapDataStoreService$ManagedConnectionFactory.getConnection(LdapDataStoreService.java:337)
              at org.forgerock.openam.entitlement.indextree.IndexChangeMonitorImpl.start(IndexChangeMonitorImpl.java:78)
              ... 7 common frames omitted
      

      This happens at such a speed that it will fill up an 8GB volume is less than a couple of minutes.

      How to reproduce the issue

      Details steps outlining how to recreate the issue (remove this text)

      1. Install an external config store
      2. Install AM out of the box configured for the external config store
      3. complete AM configuration
      4. shutdown AM
      5. shutdown config store
      6. start AM
      Expected behaviour

      AM should throw useful error messages in some log (Configuration?)

      Current behaviour

      AM fills up a disk volume extremely fast dumping repetitively message that are not helping into the Policy debug log.

      Work around

      Maybe turn off debugging...

      Code analysis

      no code analysis sorry.

        Attachments

          Activity

            People

            • Assignee:
              Unassigned
              Reporter:
              gery.ducatel Gery Ducatel
            • Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

              Dates

              • Created:
                Updated: