==================================================================================================== ========================================= Pod describe ========================================= ==================================================================================================== Name: am-59f674c5d4-frbsz Namespace: xlou Priority: 0 Node: gke-xlou-cdm-default-pool-33cc2627-kjw0/10.142.0.123 Start Time: Fri, 05 Aug 2022 06:07:57 +0000 Labels: app=am app.kubernetes.io/component=am app.kubernetes.io/instance=am app.kubernetes.io/name=am app.kubernetes.io/part-of=forgerock pod-template-hash=59f674c5d4 skaffold.dev/profile=medium skaffold.dev/run-id=xlou tier=middle Annotations: cluster-autoscaler.kubernetes.io/safe-to-evict: true Status: Running IP: 10.0.7.65 IPs: IP: 10.0.7.65 Controlled By: ReplicaSet/am-59f674c5d4 Init Containers: fbc-init: Container ID: containerd://45c8a117b16a973915876900c69c1529f8898d36e2643c1305d56942fb94318c Image: gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8 Port: Host Port: Command: /bin/bash -c if [ -d /fbc/config ]; then echo "Existing openam configuration found. Skipping copy" else echo "Copying docker image configuration files to the shared volume" cd /home/forgerock/openam cp -r .homeVersion * /fbc fi State: Terminated Reason: Completed Exit Code: 0 Started: Fri, 05 Aug 2022 06:08:02 +0000 Finished: Fri, 05 Aug 2022 06:08:02 +0000 Ready: True Restart Count: 0 Environment: Mounts: /fbc from fbc (rw) /var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-vx559 (ro) truststore-init: Container ID: containerd://587df8924dc421b988f4645a85d36b6a1f7137f9c353f99c6f5e8191780cb676 Image: gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8 Port: Host Port: Command: /home/forgerock/import-pem-certs.sh State: Terminated Reason: Completed Exit Code: 0 Started: Fri, 05 Aug 2022 06:08:03 +0000 Finished: Fri, 05 Aug 2022 06:08:03 +0000 Ready: True Restart Count: 0 Environment: TRUSTSTORE_PATH: /truststore/amtruststore TRUSTSTORE_PASSWORD: changeit AM_PEM_TRUSTSTORE: /var/run/secrets/truststore/cacerts Mounts: /truststore from new-truststore (rw) /var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-vx559 (ro) /var/run/secrets/truststore from truststore (rw) Containers: openam: Container ID: containerd://f19a144bb4b3be19bd070daa05593520e34061d5696f75ccd874142a06bb533f Image: gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8 Port: 8080/TCP Host Port: 0/TCP Command: bash /home/forgerock/docker-entrypoint.sh State: Running Started: Fri, 05 Aug 2022 06:08:04 +0000 Ready: True Restart Count: 0 Limits: memory: 10Gi Requests: cpu: 11 memory: 10Gi Liveness: http-get http://:8080/am/json/health/live delay=30s timeout=5s period=30s #success=1 #failure=3 Readiness: http-get http://:8080/am/json/health/ready delay=20s timeout=5s period=10s #success=1 #failure=3 Startup: http-get http://:8080/am/json/health/live delay=0s timeout=1s period=10s #success=1 #failure=40 Environment Variables from: am-env-secrets Secret Optional: false platform-config ConfigMap Optional: false amster-env-secrets Secret Optional: false ds-env-secrets Secret Optional: false Environment: NAMESPACE: xlou (v1:metadata.namespace) AM_STORES_SSL_ENABLED: true TRUSTSTORE_PATH: /home/forgerock/amtruststore TRUSTSTORE_PASSWORD: changeit AM_STORES_USER_TYPE: LDAPv3ForForgeRockIAM Mounts: /home/forgerock/amtruststore from new-truststore (ro,path="amtruststore") /home/forgerock/openam from fbc (rw) /var/run/secrets/am from am-secrets (rw) /var/run/secrets/amster from amster-key (rw) /var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-vx559 (ro) Conditions: Type Status Initialized True Ready True ContainersReady True PodScheduled True Volumes: fbc: Type: EmptyDir (a temporary directory that shares a pod's lifetime) Medium: SizeLimit: am-secrets: Type: Projected (a volume that contains injected data from multiple sources) SecretName: am-passwords SecretOptionalName: SecretName: am-keystore SecretOptionalName: amster-key: Type: Secret (a volume populated by a Secret) SecretName: amster Optional: false truststore: Type: Secret (a volume populated by a Secret) SecretName: truststore-pem Optional: false new-truststore: Type: EmptyDir (a temporary directory that shares a pod's lifetime) Medium: SizeLimit: kube-api-access-vx559: Type: Projected (a volume that contains injected data from multiple sources) TokenExpirationSeconds: 3607 ConfigMapName: kube-root-ca.crt ConfigMapOptional: DownwardAPI: true QoS Class: Burstable Node-Selectors: Tolerations: node.kubernetes.io/not-ready:NoExecute op=Exists for 300s node.kubernetes.io/unreachable:NoExecute op=Exists for 300s Events: Type Reason Age From Message ---- ------ ---- ---- ------- Normal Scheduled 3m15s default-scheduler Successfully assigned xlou/am-59f674c5d4-frbsz to gke-xlou-cdm-default-pool-33cc2627-kjw0 Warning FailedMount 3m15s kubelet MountVolume.SetUp failed for volume "truststore" : failed to sync secret cache: timed out waiting for the condition Warning FailedMount 3m14s kubelet MountVolume.SetUp failed for volume "am-secrets" : failed to sync secret cache: timed out waiting for the condition Warning FailedMount 3m13s (x2 over 3m15s) kubelet MountVolume.SetUp failed for volume "amster-key" : failed to sync secret cache: timed out waiting for the condition Normal Pulling 3m12s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8" Normal Created 3m11s kubelet Created container fbc-init Normal Pulled 3m11s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8" in 407.913242ms Normal Started 3m11s kubelet Started container fbc-init Normal Pulling 3m11s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8" Normal Pulling 3m10s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8" Normal Created 3m10s kubelet Created container truststore-init Normal Started 3m10s kubelet Started container truststore-init Normal Pulled 3m10s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8" in 205.800304ms Normal Pulled 3m9s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:d1dae5f855f87cdc545f2286abe3fe582de04d9ec0ba87640e41b5dde1631ea8" in 244.612754ms Normal Created 3m9s kubelet Created container openam Normal Started 3m9s kubelet Started container openam Warning Unhealthy 2m26s (x5 over 3m6s) kubelet Startup probe failed: Get "http://10.0.7.65:8080/am/json/health/live": dial tcp 10.0.7.65:8080: connect: connection refused Warning Unhealthy 115s (x3 over 2m15s) kubelet Startup probe failed: Get "http://10.0.7.65:8080/am/json/health/live": context deadline exceeded (Client.Timeout exceeded while awaiting headers) ==================================================================================================== =========================================== Pod logs =========================================== ==================================================================================================== Copying docker image configuration files to the shared volume Copying /usr/local/openjdk-11/lib/security/cacerts to /truststore/amtruststore Found (1) certificates in /var/run/secrets/truststore/cacerts Importing (1) certificates into /truststore/amtruststore Certificate was added to keystore Import complete! Copying bootstrap files for legacy AMKeyProvider Waiting for User Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Trying ds-idrepo-1.ds-idrepo:8080/alive endpoint Trying ds-idrepo-2.ds-idrepo:8080/alive endpoint Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint User Store is responding Waiting for CTS to be available Trying ds-cts-0.ds-cts:8080/alive endpoint CTS is responding Waiting for Application Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Application Store is responding Waiting for Policy Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint Policy Store is responding Waiting for UMA Store to be available Trying ds-idrepo-0.ds-idrepo:8080/alive endpoint UMA Store is responding Starting tomcat with opts: -server -Dcom.sun.services.debug.mergeall=on -Dcom.sun.identity.configuration.directory=/home/forgerock/openam -Dcom.iplanet.services.stats.state=off -Dcom.sun.identity.sm.sms_object_filebased_enabled=true -Dorg.forgerock.donotupgrade=true -DtomcatAccessLogDir=/proc/self/fd -DtomcatAccessLogFile=1 -XX:MaxRAMPercentage=75 -XX:InitialRAMPercentage=75 -XX:MaxTenuringThreshold=1 -Djava.security.egd=file:/dev/urandom -Dorg.forgerock.am.oauth2.consent.xui_path=/OAuth2_XUI -Dcom.forgerock.am.fileconfig.basepaths=/home/forgerock/base/config/services,/home/forgerock/cdk/config/services -Djavax.net.ssl.trustStore=/home/forgerock/amtruststore -Djavax.net.ssl.trustStorePassword=changeit -Djavax.net.ssl.trustStoreType=jks NOTE: Picked up JDK_JAVA_OPTIONS: --add-opens=java.base/java.lang=ALL-UNNAMED --add-opens=java.base/java.io=ALL-UNNAMED --add-opens=java.base/java.util=ALL-UNNAMED --add-opens=java.base/java.util.concurrent=ALL-UNNAMED --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED 05-Aug-2022 06:08:56.997 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name: Apache Tomcat/9.0.65 05-Aug-2022 06:08:56.999 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Jul 14 2022 12:28:53 UTC 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.65.0 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Linux 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 5.4.188+ 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /usr/local/openjdk-11 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 11.0.16+8 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /usr/local/tomcat 05-Aug-2022 06:08:57.000 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /usr/local/tomcat 05-Aug-2022 06:08:57.010 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.lang=ALL-UNNAMED 05-Aug-2022 06:08:57.010 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.io=ALL-UNNAMED 05-Aug-2022 06:08:57.010 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util=ALL-UNNAMED 05-Aug-2022 06:08:57.010 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util.concurrent=ALL-UNNAMED 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/usr/local/tomcat/conf/logging.properties 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.services.debug.mergeall=on 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.identity.configuration.directory=/home/forgerock/openam 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.iplanet.services.stats.state=off 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.identity.sm.sms_object_filebased_enabled=true 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.forgerock.donotupgrade=true 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -DtomcatAccessLogDir=/proc/self/fd 05-Aug-2022 06:08:57.011 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -DtomcatAccessLogFile=1 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:MaxRAMPercentage=75 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:InitialRAMPercentage=75 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:MaxTenuringThreshold=1 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.security.egd=file:/dev/urandom 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.forgerock.am.oauth2.consent.xui_path=/OAuth2_XUI 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.forgerock.am.fileconfig.basepaths=/home/forgerock/base/config/services,/home/forgerock/cdk/config/services 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStore=/home/forgerock/amtruststore 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStorePassword=changeit 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStoreType=jks 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs= 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/usr/local/tomcat 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/usr/local/tomcat 05-Aug-2022 06:08:57.012 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/usr/local/tomcat/temp 05-Aug-2022 06:08:57.016 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.35] using APR version [1.7.0]. 05-Aug-2022 06:08:57.016 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [true]. 05-Aug-2022 06:08:57.016 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] 05-Aug-2022 06:08:57.018 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1n 15 Mar 2022] 05-Aug-2022 06:08:57.236 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 05-Aug-2022 06:08:57.252 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8081"] 05-Aug-2022 06:08:57.253 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [411] milliseconds 05-Aug-2022 06:08:57.293 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 05-Aug-2022 06:08:57.293 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.65] 05-Aug-2022 06:08:57.298 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/am] 05-Aug-2022 06:09:04.966 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time. Starting up OpenAM at Aug 5, 2022, 6:09:07 AM {"timestamp":"2022-08-05T06:09:10.229Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:10.496Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:10.569Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"org.forgerock.openam.plugins.PluginLifecycle","message":"PluginTools#upgradePlugins: Encountered plugin org.forgerock.openam.auth.nodes.CloudOnlyNodesPlugin not configured in SMS","context":"default"} {"timestamp":"2022-08-05T06:09:12.631Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringConfig","message":"JDMK runtime not found - Legacy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:12.643Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:12.696Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:14.696Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=UNUSED.IN.FBC, port=9999, protocol=LDAP (clear-text)))' is no longer operational: Connect Error: Host 'UNUSED.IN.FBC' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:14.753Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.sm.ldap.LDAPEventManager","message":"LDAPEventManager: received all entries changed event from EventService","context":"default"} {"timestamp":"2022-08-05T06:09:14.801Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLDAPObject: got notifications, all objects changed","context":"default"} {"timestamp":"2022-08-05T06:09:15.158Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-1.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-1.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:15.427Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.sm.ldap.LDAPEventManager","message":"LDAPEventManager: received all entries changed event from EventService","context":"default"} {"timestamp":"2022-08-05T06:09:15.450Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLDAPObject: got notifications, all objects changed","context":"default"} {"timestamp":"2022-08-05T06:09:15.497Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=10, blocked=0, ldapClient=LdapClient(host=ds-idrepo-1.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-1.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:15.760Z","level":"WARN","thread":"RADIUS-RadiusServerManager","logger":"org.forgerock.openam.radius.server.config.RadiusServerManager","message":"RADIUS service is not enabled.","context":"default"} {"timestamp":"2022-08-05T06:09:16.037Z","level":"WARN","thread":"Thread-5","logger":"org.forgerock.openam.services.push.PushNotificationService","message":"No Push Notification Service Config found for realm /","context":"default","exception":"java.util.NoSuchElementException: No value present\n\tat java.base/java.util.Optional.get(Optional.java:148)\n\tat org.forgerock.openam.services.push.PushNotificationServiceConfigHelperFactory.getConfigHelperFor(PushNotificationServiceConfigHelperFactory.java:58)\n\tat org.forgerock.openam.services.push.PushNotificationService.getConfigHelper(PushNotificationService.java:280)\n\tat org.forgerock.openam.services.push.PushNotificationService.updatePreferences(PushNotificationService.java:242)\n\tat org.forgerock.openam.services.push.PushNotificationService.init(PushNotificationService.java:157)\n\tat org.forgerock.openam.services.push.PushNotificationServiceSetupListener$1.run(PushNotificationServiceSetupListener.java:48)\n\tat java.base/java.lang.Thread.run(Thread.java:829)\n"} {"timestamp":"2022-08-05T06:09:16.038Z","level":"WARN","thread":"Thread-5","logger":"org.forgerock.openam.services.push.PushNotificationService","message":"No Push Notification Delegate configured for realm /","context":"default"} {"timestamp":"2022-08-05T06:09:16.301Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.iplanet.services.naming.WebtopNaming","message":"WebtopNaming.configMonitoring: start generating ssoserverinfo\n Start time = 2022-08-05 06:09:16","context":"default"} {"timestamp":"2022-08-05T06:09:16.305Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring HTML port not enabled in configuration or JDMK not present.","context":"default"} {"timestamp":"2022-08-05T06:09:16.305Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring SNMP port not enabled or JDMK not present.","context":"default"} {"timestamp":"2022-08-05T06:09:16.305Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring RMI port not enabled.","context":"default"} {"timestamp":"2022-08-05T06:09:16.343Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLdapObject:entryExists: LdapSmsEntryUid{dn='ou=default,ou=OrganizationConfig,ou=1.0,ou=sunFMCOTConfigService,ou=services,ou=am-config', dataStoreId=DataStoreId{id='application-store'}} does not exist","context":"default"} {"timestamp":"2022-08-05T06:09:16.351Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLdapObject:entryExists: LdapSmsEntryUid{dn='ou=default,ou=OrganizationConfig,ou=1.0,ou=sunFMWSFederationMetadataService,ou=services,ou=am-config', dataStoreId=DataStoreId{id='application-store'}} does not exist","context":"default"} {"timestamp":"2022-08-05T06:09:16.359Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:16.363Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: DB password is null","context":"default"} {"timestamp":"2022-08-05T06:09:16.363Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: Logfile prefix string is null","context":"default"} {"timestamp":"2022-08-05T06:09:16.364Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: Filter class is null","context":"default"} {"timestamp":"2022-08-05T06:09:16.369Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:16.388Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:16.392Z","level":"WARN","thread":"main","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-1"},"logger":"com.sun.identity.monitoring.FederationMonitoringSetupImpl","message":"JDMK runtime not found - Federation SNMP disabled","context":"default"} 05-Aug-2022 06:09:19.216 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/am] has finished in [21,916] ms 05-Aug-2022 06:09:19.220 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"] 05-Aug-2022 06:09:19.228 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8081"] 05-Aug-2022 06:09:19.230 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [21977] milliseconds {"timestamp":"2022-08-05T06:09:19.374Z","level":"WARN","thread":"http-nio-8081-exec-1","logger":"org.forgerock.openam.core.rest.docs.api.ApiDocsService","message":"API Descriptor has changed - regenerating docs","context":"default"} {"timestamp":"2022-08-05T06:09:19.882Z","level":"ERROR","thread":"http-nio-8081-exec-1","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-145"},"logger":"org.forgerock.http.servlet.HttpFrameworkServlet","message":"Failed to write response - rootId:f3bbafba-0b2c-4839-9449-5ce6047a87a2-148","context":"default","exception":"org.apache.catalina.connector.ClientAbortException: java.io.IOException: Broken pipe\n\tat org.apache.catalina.connector.OutputBuffer.realWriteBytes(OutputBuffer.java:353)\n\tat org.apache.catalina.connector.OutputBuffer.flushByteBuffer(OutputBuffer.java:784)\n\tat org.apache.catalina.connector.OutputBuffer.append(OutputBuffer.java:689)\n\tat org.apache.catalina.connector.OutputBuffer.writeBytes(OutputBuffer.java:388)\n\tat org.apache.catalina.connector.OutputBuffer.write(OutputBuffer.java:366)\n\tat org.apache.catalina.connector.CoyoteOutputStream.write(CoyoteOutputStream.java:96)\n\tat org.forgerock.http.io.IO.stream(IO.java:290)\n\tat org.forgerock.http.servlet.HttpFrameworkServlet.writeChfResponse(HttpFrameworkServlet.java:397)\n\tat org.forgerock.http.servlet.HttpFrameworkServlet.lambda$writeAndCloseChfResponse$2(HttpFrameworkServlet.java:359)\n\tat org.forgerock.http.servlet.Servlet3Adapter$SynchronousServletSynchronizer.runIfNotCompleted(Servlet3Adapter.java:151)\n\tat org.forgerock.http.servlet.HttpFrameworkServlet.writeAndCloseChfResponse(HttpFrameworkServlet.java:359)\n\tat org.forgerock.http.servlet.HttpFrameworkServlet.lambda$service$0(HttpFrameworkServlet.java:284)\n\tat org.forgerock.util.promise.Promises$CompletedPromise.thenOnResult(Promises.java:131)\n\tat org.forgerock.http.servlet.HttpFrameworkServlet.service(HttpFrameworkServlet.java:284)\n\tat javax.servlet.http.HttpServlet.service(HttpServlet.java:764)\n\tat org.forgerock.openam.http.OpenAMHttpFrameworkServlet.service(OpenAMHttpFrameworkServlet.java:47)\n\tat javax.servlet.http.HttpServlet.service(HttpServlet.java:764)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:227)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:53)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.services.datastore.DataStoreConsistencyFilter.lambda$doFilter$0(DataStoreConsistencyFilter.java:46)\n\tat org.forgerock.openam.service.datastore.ReentrantVolatileActionConsistencyController.safeExecute(ReentrantVolatileActionConsistencyController.java:37)\n\tat org.forgerock.openam.services.datastore.DataStoreConsistencyFilter.doFilter(DataStoreConsistencyFilter.java:46)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.rest.ProtocolVersionFilter.doFilter(ProtocolVersionFilter.java:63)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.headers.SecureCookieFilter.doFilter(SecureCookieFilter.java:63)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.headers.DisableSameSiteCookiesFilter.doFilter(DisableSameSiteCookiesFilter.java:105)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.validation.ResponseValidationFilter.doFilter(ResponseValidationFilter.java:59)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.headers.SetHeadersFilter.doFilter(SetHeadersFilter.java:93)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.headers.SetHeadersFilter.doFilter(SetHeadersFilter.java:93)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.headers.SetHeadersFilter.doFilter(SetHeadersFilter.java:93)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat com.sun.identity.setup.AMSetupFilter.doFilter(AMSetupFilter.java:116)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.validation.RequestEntitySizeVerificationFilter.doFilter(RequestEntitySizeVerificationFilter.java:64)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.forgerock.openam.audit.context.AuditContextFilter.doFilter(AuditContextFilter.java:47)\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)\n\tat org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:197)\n\tat org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:97)\n\tat org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:541)\n\tat org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:135)\n\tat org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92)\n\tat org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:687)\n\tat org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:78)\n\tat org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:360)\n\tat org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:399)\n\tat org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)\n\tat org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:890)\n\tat org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1789)\n\tat org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)\n\tat org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)\n\tat org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)\n\tat org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)\n\tat java.base/java.lang.Thread.run(Thread.java:829)\nCaused by: java.io.IOException: Broken pipe\n\tat java.base/sun.nio.ch.FileDispatcherImpl.write0(Native Method)\n\tat java.base/sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)\n\tat java.base/sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:113)\n\tat java.base/sun.nio.ch.IOUtil.write(IOUtil.java:79)\n\tat java.base/sun.nio.ch.IOUtil.write(IOUtil.java:50)\n\tat java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:462)\n\tat org.apache.tomcat.util.net.NioChannel.write(NioChannel.java:135)\n\tat org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper.doWrite(NioEndpoint.java:1424)\n\tat org.apache.tomcat.util.net.SocketWrapperBase.doWrite(SocketWrapperBase.java:768)\n\tat org.apache.tomcat.util.net.SocketWrapperBase.writeBlocking(SocketWrapperBase.java:593)\n\tat org.apache.tomcat.util.net.SocketWrapperBase.write(SocketWrapperBase.java:537)\n\tat org.apache.coyote.http11.Http11OutputBuffer$SocketOutputBuffer.doWrite(Http11OutputBuffer.java:547)\n\tat org.apache.coyote.http11.filters.IdentityOutputFilter.doWrite(IdentityOutputFilter.java:73)\n\tat org.apache.coyote.http11.Http11OutputBuffer.doWrite(Http11OutputBuffer.java:194)\n\tat org.apache.coyote.Response.doWrite(Response.java:615)\n\tat org.apache.catalina.connector.OutputBuffer.realWriteBytes(OutputBuffer.java:340)\n\t... 73 common frames omitted\n"} {"timestamp":"2022-08-05T06:09:21.489Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=66, blocked=0, ldapClient=LdapClient(host=ds-cts-2.ds-cts, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-cts-2.ds-cts' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:24.672Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=2, blocked=0, ldapClient=LdapClient(host=ds-idrepo-1.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-1.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:24.673Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=2, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} 10.0.7.1 - - [05/Aug/2022:06:09:19 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 566ms 10.0.7.1 - - [05/Aug/2022:06:09:19 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 566ms 10.0.7.1 - - [05/Aug/2022:06:09:19 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 566ms 10.0.6.22 - - [05/Aug/2022:06:09:19 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 98304 626ms 10.0.7.1 - - [05/Aug/2022:06:09:27 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.6.22 - - [05/Aug/2022:06:09:31 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 193191 23ms 10.0.7.1 - - [05/Aug/2022:06:09:37 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms {"timestamp":"2022-08-05T06:09:44.304Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-165"},"logger":"org.forgerock.openam.core.realms.impl.DefaultRealmLookup","message":"DefaultRealms:lookup Unable to find Org name for: authenticate","context":"default"} {"timestamp":"2022-08-05T06:09:44.305Z","level":"DEBUG","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-165"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-05T06:09:44.305Z","level":"DEBUG","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-165"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-05T06:09:44.480Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-165"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module OpenIdConnect","context":"default"} {"timestamp":"2022-08-05T06:09:44.481Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-165"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module AuthenticatorPushRegistration","context":"default"} {"timestamp":"2022-08-05T06:09:44.482Z","level":"WARN","thread":"http-nio-8081-exec-3","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-165"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module PersistentCookie","context":"default"} {"timestamp":"2022-08-05T06:09:44.536Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-165","trackingIds":["f3bbafba-0b2c-4839-9449-5ce6047a87a2-168"],"client":{"ip":"10.0.7.64","port":33936},"http":{"request":{"secure":true,"method":"POST","path":"https://am:80/am/json/authenticate","headers":{"accept-api-version":["protocol=2.1"],"content-type":["application/json; charset=UTF-8"],"host":["am:80"],"user-agent":["Apache-HttpAsyncClient/4.1.4 (Java/11.0.16)"],"x-requested-with":["Amster"]},"queryParameters":{"authIndexType":["service"],"authIndexValue":["amsterService"]}}},"response":{"status":"SUCCESSFUL","statusCode":"200","elapsedTime":281,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-170"} {"timestamp":"2022-08-05T06:09:44.665Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-05T06:09:44.665Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-05T06:09:44.688Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:44.784Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171"},"logger":"com.sun.identity.idm.plugins.internal.AgentsRepo","message":"AgentsRepo.getAgentGroupConfig: Unable to get Agent Group Config due to The instance agentgroup does not exist","context":"default"} {"timestamp":"2022-08-05T06:09:44.839Z","eventName":"AM-LOGIN-MODULE-COMPLETED","transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171","trackingIds":["f3bbafba-0b2c-4839-9449-5ce6047a87a2-168"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"Amster","info":{"authIndex":"service","authControlFlag":"REQUIRED","moduleClass":"Amster","ipAddress":"10.0.7.64","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-175"} {"timestamp":"2022-08-05T06:09:45.013Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-05T06:09:45.003Z","eventName":"AM-SESSION-CREATED","transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171","trackingIds":["f3bbafba-0b2c-4839-9449-5ce6047a87a2-168"],"userId":"id=amadmin,ou=user,ou=am-config","runAs":"id=amadmin,ou=user,ou=am-config","objectId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-168","operation":"CREATE","realm":"/","component":"Session","source":"audit","topic":"activity","level":"INFO","_eventId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-182"} {"timestamp":"2022-08-05T06:09:45.024Z","eventName":"AM-LOGIN-COMPLETED","transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171","trackingIds":["f3bbafba-0b2c-4839-9449-5ce6047a87a2-168"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"Amster","info":{"authIndex":"service","authIndexValue":"amsterService","ipAddress":"10.0.7.64","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-186"} {"timestamp":"2022-08-05T06:09:45.043Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-171","trackingIds":["f3bbafba-0b2c-4839-9449-5ce6047a87a2-168"],"userId":"id=amadmin,ou=user,ou=am-config","client":{"ip":"10.0.7.64","port":33936},"http":{"request":{"secure":true,"method":"POST","path":"https://am:80/am/json/authenticate","headers":{"accept-api-version":["protocol=2.1"],"content-type":["application/json; charset=UTF-8"],"host":["am:80"],"user-agent":["Apache-HttpAsyncClient/4.1.4 (Java/11.0.16)"],"x-requested-with":["Amster"]},"queryParameters":{"authIndexType":["service"],"authIndexValue":["amsterService"]}}},"response":{"status":"SUCCESSFUL","statusCode":"200","elapsedTime":379,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-192"} {"timestamp":"2022-08-05T06:09:45.055Z","level":"WARN","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-193"},"logger":"org.forgerock.openam.core.realms.impl.DefaultRealmLookup","message":"DefaultRealms:lookup Unable to find Org name for: serverinfo","context":"default"} {"timestamp":"2022-08-05T06:09:45.055Z","level":"DEBUG","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-193"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-05T06:09:45.055Z","level":"DEBUG","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-193"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-05T06:09:45.105Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-193","client":{"ip":"10.0.7.64","port":33936},"server":{"ip":"10.0.7.65","port":8081},"http":{"request":{"secure":true,"method":"GET","path":"https://am:80/am/json/serverinfo/*","headers":{"accept-api-version":["protocol=2.1"],"host":["am:80"],"user-agent":["Apache-HttpAsyncClient/4.1.4 (Java/11.0.16)"]}}},"request":{"protocol":"CREST","operation":"READ"},"response":{"status":"SUCCESSFUL","statusCode":"","elapsedTime":57,"elapsedTimeUnits":"MILLISECONDS","detail":{"revision":"-485253810"}},"realm":"/","component":"Server Info","source":"audit","topic":"access","level":"INFO","_eventId":"f3bbafba-0b2c-4839-9449-5ce6047a87a2-197"} 10.0.7.64 - - [05/Aug/2022:06:09:44 +0000] "POST /am/json/authenticate?authIndexType=service&authIndexValue=amsterService HTTP/1.1" 200 668 296ms 10.0.7.64 - - [05/Aug/2022:06:09:45 +0000] "POST /am/json/authenticate?authIndexType=service&authIndexValue=amsterService HTTP/1.1" 200 167 382ms 10.0.7.64 - - [05/Aug/2022:06:09:45 +0000] "GET /am/json/serverinfo/* HTTP/1.1" 200 544 67ms 10.0.7.1 - - [05/Aug/2022:06:09:47 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms {"timestamp":"2022-08-05T06:09:54.766Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=14, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:54.767Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=14, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} {"timestamp":"2022-08-05T06:09:54.768Z","level":"WARN","thread":"LDAP SDK Default Scheduler","logger":"org.forgerock.opendj.ldap.LoadBalancer","message":"Connection factory 'CachedConnectionPool(size=0[in:0 + out:0 + pending:0], maxSize=2, blocked=0, ldapClient=LdapClient(host=ds-idrepo-2.ds-idrepo, port=1636, protocol=LDAPS))' is no longer operational: Connect Error: Host 'ds-idrepo-2.ds-idrepo' cannot be resolved","context":"default"} 10.0.7.1 - - [05/Aug/2022:06:09:57 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.7.1 - - [05/Aug/2022:06:09:57 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.6.22 - - [05/Aug/2022:06:10:01 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349804 41ms 10.0.7.1 - - [05/Aug/2022:06:10:07 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.7.1 - - [05/Aug/2022:06:10:17 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.7.1 - - [05/Aug/2022:06:10:27 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.7.1 - - [05/Aug/2022:06:10:27 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 2ms 10.0.6.22 - - [05/Aug/2022:06:10:31 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349806 31ms 10.0.7.1 - - [05/Aug/2022:06:10:37 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.6.14 - - [05/Aug/2022:06:10:43 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 4ms 10.0.7.1 - - [05/Aug/2022:06:10:47 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.7.1 - - [05/Aug/2022:06:10:57 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.7.1 - - [05/Aug/2022:06:10:57 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.6.22 - - [05/Aug/2022:06:11:01 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349806 31ms 10.0.7.1 - - [05/Aug/2022:06:11:07 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms