==================================================================================================== ========================================= Pod describe ========================================= ==================================================================================================== Name: am-5d5df7bfb9-7nthj Namespace: xlou Priority: 0 Node: gke-xlou-cdm-default-pool-33cc2627-n18j/10.142.0.124 Start Time: Sun, 07 Aug 2022 16:13:28 +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=5d5df7bfb9 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.8.84 IPs: IP: 10.0.8.84 Controlled By: ReplicaSet/am-5d5df7bfb9 Init Containers: fbc-init: Container ID: containerd://9bbf5837aae090612d28f4143e3ddce4ec93e0a3c00fe7cfe0d5207507b11114 Image: gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 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: Sun, 07 Aug 2022 16:13:29 +0000 Finished: Sun, 07 Aug 2022 16:13:29 +0000 Ready: True Restart Count: 0 Environment: Mounts: /fbc from fbc (rw) /var/run/secrets/kubernetes.io/serviceaccount from kube-api-access-9j4pj (ro) truststore-init: Container ID: containerd://f2745ea846f75bb730cc1e450b638bce8e79f511d25561807730c43eb09d8290 Image: gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Port: Host Port: Command: /home/forgerock/import-pem-certs.sh State: Terminated Reason: Completed Exit Code: 0 Started: Sun, 07 Aug 2022 16:13:30 +0000 Finished: Sun, 07 Aug 2022 16:13:30 +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-9j4pj (ro) /var/run/secrets/truststore from truststore (rw) Containers: openam: Container ID: containerd://d68043e378ebd6bea2f90dacb4a0b3c655cfb8c377d3e109700ecc031ef6f63d Image: gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Image ID: gcr.io/engineeringpit/lodestar-images/am@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4 Port: 8080/TCP Host Port: 0/TCP Command: bash /home/forgerock/docker-entrypoint.sh State: Running Started: Sun, 07 Aug 2022 16:13:31 +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-9j4pj (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-9j4pj: 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 3m18s default-scheduler Successfully assigned xlou/am-5d5df7bfb9-7nthj to gke-xlou-cdm-default-pool-33cc2627-n18j Normal Pulled 3m18s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" in 244.211732ms Normal Created 3m18s kubelet Created container fbc-init Normal Started 3m18s kubelet Started container fbc-init Normal Pulling 3m18s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" Normal Pulling 3m18s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" Normal Pulling 3m17s kubelet Pulling image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" Normal Pulled 3m17s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" in 192.350461ms Normal Started 3m17s kubelet Started container truststore-init Normal Created 3m17s kubelet Created container truststore-init Normal Pulled 3m16s kubelet Successfully pulled image "gcr.io/engineeringpit/lodestar-images/am:xlou@sha256:e2be3b24f724e416b86384b94cb9d1636008c6b3248a5b6f65e5a88ac6c555b4" in 233.167184ms Normal Created 3m16s kubelet Created container openam Normal Started 3m16s kubelet Started container openam Warning Unhealthy 2m19s (x6 over 3m9s) kubelet Startup probe failed: Get "http://10.0.8.84:8080/am/json/health/live": dial tcp 10.0.8.84:8080: connect: connection refused Warning Unhealthy 118s (x2 over 2m8s) kubelet Startup probe failed: Get "http://10.0.8.84: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 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 07-Aug-2022 16:14:29.731 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name: Apache Tomcat/9.0.65 07-Aug-2022 16:14:29.733 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Jul 14 2022 12:28:53 UTC 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.65.0 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Linux 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 5.4.188+ 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /usr/local/openjdk-11 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 11.0.16+8 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /usr/local/tomcat 07-Aug-2022 16:14:29.734 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /usr/local/tomcat 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.lang=ALL-UNNAMED 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.io=ALL-UNNAMED 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util=ALL-UNNAMED 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util.concurrent=ALL-UNNAMED 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/usr/local/tomcat/conf/logging.properties 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 07-Aug-2022 16:14:29.743 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.services.debug.mergeall=on 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.identity.configuration.directory=/home/forgerock/openam 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.iplanet.services.stats.state=off 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.identity.sm.sms_object_filebased_enabled=true 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.forgerock.donotupgrade=true 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -DtomcatAccessLogDir=/proc/self/fd 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -DtomcatAccessLogFile=1 07-Aug-2022 16:14:29.744 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:MaxRAMPercentage=75 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:InitialRAMPercentage=75 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:MaxTenuringThreshold=1 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.security.egd=file:/dev/urandom 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.forgerock.am.oauth2.consent.xui_path=/OAuth2_XUI 07-Aug-2022 16:14:29.745 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 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStore=/home/forgerock/amtruststore 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStorePassword=changeit 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djavax.net.ssl.trustStoreType=jks 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs= 07-Aug-2022 16:14:29.745 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/usr/local/tomcat 07-Aug-2022 16:14:29.746 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/usr/local/tomcat 07-Aug-2022 16:14:29.746 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/usr/local/tomcat/temp 07-Aug-2022 16:14:29.749 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.35] using APR version [1.7.0]. 07-Aug-2022 16:14:29.749 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [true]. 07-Aug-2022 16:14:29.749 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] 07-Aug-2022 16:14:29.751 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1n 15 Mar 2022] 07-Aug-2022 16:14:29.970 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 07-Aug-2022 16:14:29.988 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8081"] 07-Aug-2022 16:14:29.988 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [408] milliseconds 07-Aug-2022 16:14:30.029 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 07-Aug-2022 16:14:30.030 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.65] 07-Aug-2022 16:14:30.034 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/am] 07-Aug-2022 16:14:37.350 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 7, 2022, 4:14:39 PM {"timestamp":"2022-08-07T16:14:42.777Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:14:43.044Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:14:43.114Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:14:45.184Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringConfig","message":"JDMK runtime not found - Legacy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:14:45.196Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:14:45.251Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:14:47.328Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:14:47.385Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.sm.ldap.LDAPEventManager","message":"LDAPEventManager: received all entries changed event from EventService","context":"default"} {"timestamp":"2022-08-07T16:14:47.433Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLDAPObject: got notifications, all objects changed","context":"default"} {"timestamp":"2022-08-07T16:14:47.689Z","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-07T16:14:48.045Z","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-07T16:14:48.072Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.sm.ldap.LDAPEventManager","message":"LDAPEventManager: received all entries changed event from EventService","context":"default"} {"timestamp":"2022-08-07T16:14:48.094Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.sm.ldap.SMSLdapObject","message":"SMSLDAPObject: got notifications, all objects changed","context":"default"} {"timestamp":"2022-08-07T16:14:48.386Z","level":"WARN","thread":"RADIUS-RadiusServerManager","logger":"org.forgerock.openam.radius.server.config.RadiusServerManager","message":"RADIUS service is not enabled.","context":"default"} {"timestamp":"2022-08-07T16:14:48.817Z","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-07T16:14:48.818Z","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-07T16:14:49.068Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.iplanet.services.naming.WebtopNaming","message":"WebtopNaming.configMonitoring: start generating ssoserverinfo\n Start time = 2022-08-07 16:14:49","context":"default"} {"timestamp":"2022-08-07T16:14:49.071Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:14:49.071Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring SNMP port not enabled or JDMK not present.","context":"default"} {"timestamp":"2022-08-07T16:14:49.072Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringAdapters","message":"Agent.startAgent:Monitoring RMI port not enabled.","context":"default"} {"timestamp":"2022-08-07T16:14:49.105Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:14:49.113Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:14:49.120Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:14:49.124Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: DB password is null","context":"default"} {"timestamp":"2022-08-07T16:14:49.124Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: Logfile prefix string is null","context":"default"} {"timestamp":"2022-08-07T16:14:49.125Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.log.s1is.LogConfigReader","message":"LogConfigReader: Filter class is null","context":"default"} {"timestamp":"2022-08-07T16:14:49.129Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:14:49.146Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:14:49.149Z","level":"WARN","thread":"main","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-1"},"logger":"com.sun.identity.monitoring.FederationMonitoringSetupImpl","message":"JDMK runtime not found - Federation SNMP disabled","context":"default"} 07-Aug-2022 16:14:51.911 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/am] has finished in [21,875] ms 07-Aug-2022 16:14:51.915 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"] 07-Aug-2022 16:14:51.924 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8081"] 07-Aug-2022 16:14:51.926 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [21937] milliseconds {"timestamp":"2022-08-07T16:14:52.075Z","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-07T16:14:52.608Z","level":"ERROR","thread":"http-nio-8081-exec-1","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-143"},"logger":"org.forgerock.http.servlet.HttpFrameworkServlet","message":"Failed to write response - rootId:103a5fab-7bae-4e99-9b8a-8be321994089-147","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-07T16:14:53.964Z","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-07T16:14:57.308Z","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"} {"timestamp":"2022-08-07T16:14:57.308Z","level":"WARN","thread":"LDAP SDK Default AsyncRx I/O Thread (2)","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: Connection refused","context":"default"} 10.0.8.1 - - [07/Aug/2022:16:14:52 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 584ms 10.0.8.1 - - [07/Aug/2022:16:14:52 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 584ms 10.0.6.22 - - [07/Aug/2022:16:14:52 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 98304 655ms 10.0.8.1 - - [07/Aug/2022:16:14:58 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.6.22 - - [07/Aug/2022:16:15:03 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 193270 22ms 10.0.8.1 - - [07/Aug/2022:16:15:08 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms {"timestamp":"2022-08-07T16:15:16.494Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-165"},"logger":"org.forgerock.openam.core.realms.impl.DefaultRealmLookup","message":"DefaultRealms:lookup Unable to find Org name for: authenticate","context":"default"} {"timestamp":"2022-08-07T16:15:16.495Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-165"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:15:16.495Z","level":"DEBUG","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-165"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:15:16.676Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-165"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module OpenIdConnect","context":"default"} {"timestamp":"2022-08-07T16:15:16.677Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-165"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module AuthenticatorPushRegistration","context":"default"} {"timestamp":"2022-08-07T16:15:16.678Z","level":"WARN","thread":"http-nio-8081-exec-4","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-165"},"logger":"com.sun.identity.authentication.config.AMAuthLevelManager","message":"No auth level for module PersistentCookie","context":"default"} {"timestamp":"2022-08-07T16:15:16.735Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-165","trackingIds":["103a5fab-7bae-4e99-9b8a-8be321994089-168"],"client":{"ip":"10.0.7.133","port":34988},"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":293,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"103a5fab-7bae-4e99-9b8a-8be321994089-170"} {"timestamp":"2022-08-07T16:15:16.860Z","level":"DEBUG","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:15:16.860Z","level":"DEBUG","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:15:16.881Z","level":"WARN","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:15:16.986Z","level":"WARN","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-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-07T16:15:17.033Z","eventName":"AM-LOGIN-MODULE-COMPLETED","transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171","trackingIds":["103a5fab-7bae-4e99-9b8a-8be321994089-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.133","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"103a5fab-7bae-4e99-9b8a-8be321994089-175"} {"timestamp":"2022-08-07T16:15:17.203Z","level":"WARN","thread":"http-nio-8081-exec-5","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171"},"logger":"com.sun.identity.monitoring.MonitoringServicesImpl","message":"JDMK runtime not found - Policy Monitoring disabled","context":"default"} {"timestamp":"2022-08-07T16:15:17.197Z","eventName":"AM-SESSION-CREATED","transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171","trackingIds":["103a5fab-7bae-4e99-9b8a-8be321994089-168"],"userId":"id=amadmin,ou=user,ou=am-config","runAs":"id=amadmin,ou=user,ou=am-config","objectId":"103a5fab-7bae-4e99-9b8a-8be321994089-168","operation":"CREATE","realm":"/","component":"Session","source":"audit","topic":"activity","level":"INFO","_eventId":"103a5fab-7bae-4e99-9b8a-8be321994089-182"} {"timestamp":"2022-08-07T16:15:17.214Z","eventName":"AM-LOGIN-COMPLETED","transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171","trackingIds":["103a5fab-7bae-4e99-9b8a-8be321994089-168"],"userId":"id=amadmin,ou=user,ou=am-config","principal":["amadmin"],"entries":[{"moduleId":"Amster","info":{"authIndex":"service","authIndexValue":"amsterService","ipAddress":"10.0.7.133","authLevel":"0"}}],"result":"SUCCESSFUL","realm":"/","component":"Authentication","source":"audit","topic":"authentication","level":"INFO","_eventId":"103a5fab-7bae-4e99-9b8a-8be321994089-186"} {"timestamp":"2022-08-07T16:15:17.233Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-171","trackingIds":["103a5fab-7bae-4e99-9b8a-8be321994089-168"],"userId":"id=amadmin,ou=user,ou=am-config","client":{"ip":"10.0.7.133","port":34988},"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":374,"elapsedTimeUnits":"MILLISECONDS"},"realm":"/","component":"Authentication","source":"audit","topic":"access","level":"INFO","_eventId":"103a5fab-7bae-4e99-9b8a-8be321994089-192"} {"timestamp":"2022-08-07T16:15:17.243Z","level":"WARN","thread":"http-nio-8081-exec-6","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-193"},"logger":"org.forgerock.openam.core.realms.impl.DefaultRealmLookup","message":"DefaultRealms:lookup Unable to find Org name for: serverinfo","context":"default"} {"timestamp":"2022-08-07T16:15:17.244Z","level":"DEBUG","thread":"http-nio-8081-exec-6","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-193"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:15:17.244Z","level":"DEBUG","thread":"http-nio-8081-exec-6","mdc":{"transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-193"},"logger":"org.forgerock.openam.cors.CorsFilter","message":"handling non-CORS request","context":"default"} {"timestamp":"2022-08-07T16:15:17.279Z","eventName":"AM-ACCESS-OUTCOME","transactionId":"103a5fab-7bae-4e99-9b8a-8be321994089-193","client":{"ip":"10.0.7.133","port":34988},"server":{"ip":"10.0.8.84","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":40,"elapsedTimeUnits":"MILLISECONDS","detail":{"revision":"-485253810"}},"realm":"/","component":"Server Info","source":"audit","topic":"access","level":"INFO","_eventId":"103a5fab-7bae-4e99-9b8a-8be321994089-197"} 10.0.7.133 - - [07/Aug/2022:16:15:13 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 4ms 10.0.7.133 - - [07/Aug/2022:16:15:16 +0000] "POST /am/json/authenticate?authIndexType=service&authIndexValue=amsterService HTTP/1.1" 200 668 307ms 10.0.7.133 - - [07/Aug/2022:16:15:17 +0000] "POST /am/json/authenticate?authIndexType=service&authIndexValue=amsterService HTTP/1.1" 200 167 378ms 10.0.7.133 - - [07/Aug/2022:16:15:17 +0000] "GET /am/json/serverinfo/* HTTP/1.1" 200 544 49ms 10.0.8.1 - - [07/Aug/2022:16:15:18 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms {"timestamp":"2022-08-07T16:15:26.938Z","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-07T16:15:26.941Z","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-07T16:15:26.943Z","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.8.1 - - [07/Aug/2022:16:15:28 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.8.1 - - [07/Aug/2022:16:15:28 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.6.22 - - [07/Aug/2022:16:15:33 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349786 42ms 10.0.8.1 - - [07/Aug/2022:16:15:38 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.8.1 - - [07/Aug/2022:16:15:48 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.8.1 - - [07/Aug/2022:16:15:58 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 3ms 10.0.8.1 - - [07/Aug/2022:16:15:58 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.6.22 - - [07/Aug/2022:16:16:03 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349772 32ms 10.0.8.1 - - [07/Aug/2022:16:16:08 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.6.14 - - [07/Aug/2022:16:16:15 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 4ms 10.0.8.1 - - [07/Aug/2022:16:16:18 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.8.1 - - [07/Aug/2022:16:16:28 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms 10.0.8.1 - - [07/Aug/2022:16:16:28 +0000] "GET /am/json/health/live HTTP/1.1" 200 - 3ms 10.0.6.22 - - [07/Aug/2022:16:16:33 +0000] "GET /am/json/metrics/prometheus HTTP/1.1" 200 349766 31ms 10.0.8.1 - - [07/Aug/2022:16:16:38 +0000] "GET /am/json/health/ready HTTP/1.1" 200 - 2ms