Hi,
after adding a new Application Server to our environment, on which xFlow should be installed, we can not access the login page, which should be accessible via <server>:9002 for xFlow.
Instead we get "page is not reachable" - "ERR_CONNECTION_REFUSED"
The SDM service is up and running and we can reach the server in our Advance Availability environment correctly in the classic UI mode.
The firewall is off and port 9002 is in the rule set nevertheless, but netstat don't show me a listening port.
The xFlow service looks running fine in the Service control panel. But several log files show errors.
Even on the server directly I cannot reach the login page. Not with servernamne, nor IP, nor localhost:9002
The setup is an Advanced Availabilty SDM on 17.3.0.6 on Windows Server 2016 OS with remote MS SQL DB (SQL Sever 2016)
1 BG
1 SB
3 App Servers
I have restarted the whole environment already. And when starting xFlow on the 3rd App server at last, following log files get updated:
xFlow\APPS\logs\xFlowService.log
--------------------------------
[2021-07-20 17:13:59] Log File Path = C:\PROGRA~1\CA\xFlow\APPS\\logs\xFlowService.log
[2021-07-20 17:13:59] Starting Service - No. Of Arguments Passed: 1$Argument[0] = {C:\Program Files\CA\xFlow\APPS\ServiceConfig.xml}
[2021-07-20 17:13:59] programfiles = C:\Program Files
[2021-07-20 17:13:59] default JRE = C:\PROGRA~1\\CA\SC\jre\11.0.3
[2021-07-20 17:13:59] Stopping Service - No. Of Arguments Passed: 2$Argument[0] = {C:\Program Files\CA\xFlow\APPS\ServiceConfig.xml}
$Argument[1] = {LOCALSTOP}
[2021-07-20 17:13:59] About to kill Incident Micro Service...
[2021-07-20 17:13:59] About to kill Search Micro Service...
[2021-07-20 17:13:59] About to kill Push Micro Service...
[2021-07-20 17:13:59] About to kill Collab Micro Service...
[2021-07-20 17:13:59] About to kill Insight Micro Service...
[2021-07-20 17:13:59] Working Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\incidentmicroservice-17.0.479\bin
[2021-07-20 17:13:59] App Home Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\incidentmicroservice-17.0.479
[2021-07-20 17:13:59] Service Batch Path = C:\PROGRA~1\CA\xFlow\APPS\\Services\incidentmicroservice-17.0.479\bin\incidentmicroservice.bat
[2021-07-20 17:13:59] Service PID File = C:\PROGRA~1\CA\xFlow\APPS\\Services\incidentmicroservice-17.0.479\bin\RUNNING_PID
[2021-07-20 17:13:59] Service Port = 9002
[2021-07-20 17:13:59] Starting Micro Service....
[2021-07-20 17:13:59] Setting redirect output & error logs for the process....out =C:\PROGRA~1\CA\xFlow\APPS\\logs\incidentservicestdout.txt $ err =C:\PROGRA~1\CA\xFlow\APPS\\logs\incidentservicestderr.txt
[2021-07-20 17:13:59] Micro Service Started
[2021-07-20 17:14:02] Working Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\searchmicroservice-17.0.479\bin
[2021-07-20 17:14:02] App Home Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\searchmicroservice-17.0.479
[2021-07-20 17:14:02] Service Batch Path = C:\PROGRA~1\CA\xFlow\APPS\\Services\searchmicroservice-17.0.479\bin\searchmicroservice.bat
[2021-07-20 17:14:02] Service PID File = C:\PROGRA~1\CA\xFlow\APPS\\Services\searchmicroservice-17.0.479\bin\RUNNING_PID
[2021-07-20 17:14:02] Service Port = 9006
[2021-07-20 17:14:02] Starting Micro Service....
[2021-07-20 17:14:02] Setting redirect output & error logs for the process....out =C:\PROGRA~1\CA\xFlow\APPS\\logs\searchservicestdout.txt $ err =C:\PROGRA~1\CA\xFlow\APPS\\logs\searchservicestderr.txt
[2021-07-20 17:14:02] Micro Service Started
[2021-07-20 17:14:03] Working Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\pushmicroservice-17.0.479\bin
[2021-07-20 17:14:03] App Home Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\pushmicroservice-17.0.479
[2021-07-20 17:14:03] Service Batch Path = C:\PROGRA~1\CA\xFlow\APPS\\Services\pushmicroservice-17.0.479\bin\pushmicroservice.bat
[2021-07-20 17:14:03] Service PID File = C:\PROGRA~1\CA\xFlow\APPS\\Services\pushmicroservice-17.0.479\bin\RUNNING_PID
[2021-07-20 17:14:03] Service Port = 9008
[2021-07-20 17:14:03] Starting Micro Service....
[2021-07-20 17:14:04] Setting redirect output & error logs for the process....out =C:\PROGRA~1\CA\xFlow\APPS\\logs\pushservicestdout.txt $ err =C:\PROGRA~1\CA\xFlow\APPS\\logs\pushservicestderr.txt
[2021-07-20 17:14:04] Micro Service Started
[2021-07-20 17:14:05] Working Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\collabmicroservice-17.0.479\bin
[2021-07-20 17:14:05] App Home Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\collabmicroservice-17.0.479
[2021-07-20 17:14:05] Service Batch Path = C:\PROGRA~1\CA\xFlow\APPS\\Services\collabmicroservice-17.0.479\bin\collabmicroservice.bat
[2021-07-20 17:14:05] Service PID File = C:\PROGRA~1\CA\xFlow\APPS\\Services\collabmicroservice-17.0.479\bin\RUNNING_PID
[2021-07-20 17:14:05] Service Port = 9014
[2021-07-20 17:14:05] Starting Micro Service....
[2021-07-20 17:14:05] Setting redirect output & error logs for the process....out =C:\PROGRA~1\CA\xFlow\APPS\\logs\collabservicestdout.txt $ err =C:\PROGRA~1\CA\xFlow\APPS\\logs\collabservicestderr.txt
[2021-07-20 17:14:05] Micro Service Started
[2021-07-20 17:14:05] Working Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\insightmicroservice-17.1.706\bin
[2021-07-20 17:14:05] App Home Directory = C:\PROGRA~1\CA\xFlow\APPS\\Services\insightmicroservice-17.1.706
[2021-07-20 17:14:05] Service Batch Path = C:\PROGRA~1\CA\xFlow\APPS\\Services\insightmicroservice-17.1.706\bin\insightmicroservice.bat
[2021-07-20 17:14:05] Service PID File = C:\PROGRA~1\CA\xFlow\APPS\\Services\insightmicroservice-17.1.706\bin\RUNNING_PID
[2021-07-20 17:14:05] Service Port = 9016
[2021-07-20 17:14:05] Starting Micro Service....
[2021-07-20 17:14:05] Setting redirect output & error logs for the process....out =C:\PROGRA~1\CA\xFlow\APPS\\logs\insightservicestdout.txt $ err =C:\PROGRA~1\CA\xFlow\APPS\\logs\insightservicestderr.txt
[2021-07-20 17:14:05] Micro Service Started
xFlow\APPS\logs\commons-daemon.2021-07-20.log
---------------------------------------------
[2021-07-20 17:13:58] [info] [ 6928] Commons Daemon procrun (1.0.15.0 64-bit) started
[2021-07-20 17:13:58] [info] [ 6928] Running 'xFlowServer' Service...
[2021-07-20 17:13:58] [info] [11084] Starting service...
[2021-07-20 17:14:00] [info] [11084] Service started in 1249 ms.
xFlow\APPS\logs\incidentMS.log
------------------------------
INFO - 2021-07-20 17:14:03 [modules.IncidentMSModule] - Reading the Module Configurations.......
INFO - 2021-07-20 17:14:18 [n.s.e.p.s.f.AnnotationSizeOfFilter] - Using regular expression provided through VM argument net.sf.ehcache.pool.sizeof.ignore.pattern for IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
INFO - 2021-07-20 17:14:19 [n.s.e.pool.sizeof.JvmInformation] - Detected JVM data model settings of: 64-Bit OpenJDK JVM with Compressed OOPs
INFO - 2021-07-20 17:14:19 [n.s.ehcache.pool.sizeof.AgentLoader] - Failed to attach to VM and load the agent: class java.io.IOException: Can not attach to current VM
INFO - 2021-07-20 17:14:19 [n.s.e.pool.impl.DefaultSizeOfEngine] - using Unsafe sizeof engine
INFO - 2021-07-20 17:14:19 [n.s.e.pool.impl.DefaultSizeOfEngine] - using Unsafe sizeof engine
INFO - 2021-07-20 17:14:19 [n.s.e.pool.impl.DefaultSizeOfEngine] - using Unsafe sizeof engine
INFO - 2021-07-20 17:14:19 [n.s.e.pool.impl.DefaultSizeOfEngine] - using Unsafe sizeof engine
INFO - 2021-07-20 17:14:21 [akka.event.slf4j.Slf4jLogger] - Slf4jLogger started
INFO - 2021-07-20 17:14:23 [application] - Connecting to Slump
INFO - 2021-07-20 17:14:26 [global.GlobalApplicationSettings] - APP_CODE: 0
INFO - 2021-07-20 17:14:26 [global.GlobalApplicationSettings] - APP_NAME: Incident MicroService
INFO - 2021-07-20 17:14:26 [global.GlobalApplicationSettings] - APP_ROOT_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\incidentmicroservice-17.0.479\
INFO - 2021-07-20 17:14:26 [global.GlobalApplicationSettings] - APP_LIB_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\incidentmicroservice-17.0.479\common\target\scala-2.12\classes;..\common\target\scala-2.12\classes;C:\PROGRA~1\CA\xFlow\APPS\Services\incidentmicroservice-17.0.479\lib\
INFO - 2021-07-20 17:14:26 [global.GlobalApplicationSettings] - Generating SDM POJOs to location C:\PROGRA~1\CA\xFlow\APPS\\temp\CasmPlayApi\Source\Pojo\
INFO - 2021-07-20 17:14:26 [com.ca.casm.utils.Utils] - The Service Desk environment is non-tenanted.
INFO - 2021-07-20 17:14:26 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock acquired: true, is shared: false
INFO - 2021-07-20 17:14:35 [com.ca.casm.sdm.SdmPojoProvider] - Skipping POJO generation as majic checksums match!
INFO - 2021-07-20 17:14:43 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock released: true
INFO - 2021-07-20 17:14:46 [global.GlobalApplicationSettings] - Unit Test ---> PriPOJO Entity Name = pri
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:encryptRoleID
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.audienceuris
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.enableManualRedirect
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.realm
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.reply
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.friendlyname
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.issuer
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.thumbprint
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:Incident_MicroService_akka.cluster.seed-nodes.0
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.context
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.domain
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.hostname
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.insightserver.name
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.requestimeout
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.theme
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.protocol
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:sso.secured.cookies
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - KD Settings: SUBMIT_COMMENTS = 1
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - KD Settings: VIEW_COMMENTS = 1
INFO - 2021-07-20 17:14:47 [com.ca.casm.utils.Utils] - log4jPropFilePath: C:\PROGRA~1\CA\xFlow\APPS\Services\incidentmicroservice-17.0.479\\conf\log4j.properties
INFO - 2021-07-20 17:14:47 [com.ca.casm.utils.Utils] - log4jRefreshInterval variable is null or empty. Defaulting the refresh interval to 300 seconds.
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - Started loading cache_info from database and ehcache.xml file.
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cache_info
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cache_info
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from CA SDM):itpam_tasks_info_cache#max#entry:25000
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from xml):my_context#max#entry:1
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_auth
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_auth
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache related to role authorization.
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for functional access role.
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_access
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - Total role objects : 46
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_access
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:dcon
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - Total func_access_role object count : 1664
ERROR - 2021-07-20 17:14:47 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$12(CacheLoader.java:762)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for dcon
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for data partition.
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp_role
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - Total data partition record count is 178
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp_role
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type role.
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp
INFO - 2021-07-20 17:14:47 [c.ca.casm.authorization.CacheLoader] - Total acctyp_role record count is 119
INFO - 2021-07-20 17:14:47 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp
INFO - 2021-07-20 17:14:48 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type objects.
INFO - 2021-07-20 17:14:48 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:14:48 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: object_customized
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: bui_object_customized
INFO - 2021-07-20 17:14:48 [c.ca.casm.authorization.Authorizer] - Register for changes initialization started for authorization module cache.
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: role
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: func_access_role
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: dcon
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tenant
ERROR - 2021-07-20 17:14:48 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$16(CacheLoader.java:1106)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tenant
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tenant
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tgm_members
ERROR - 2021-07-20 17:14:48 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$loadAccTypeCache$20(CacheLoader.java:1387)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tgm_members
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tgm_members
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cnt
INFO - 2021-07-20 17:14:48 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cnt
ERROR - 2021-07-20 17:14:51 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$18(CacheLoader.java:1249)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: cnt
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp_role
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: user_context
INFO - 2021-07-20 17:14:51 [c.ca.casm.authorization.Authorizer] - Register for changes finished registration with SDM.
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: attr_control
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:master_data_cache
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for master_data_cache
INFO - 2021-07-20 17:14:51 [c.c.c.a.cache.MasterDataCacheMgr] - Caching for master data factories is disabled.
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:stream_attrs
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for stream_attrs
INFO - 2021-07-20 17:14:51 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: stream_eligible_attrs
ERROR - 2021-07-20 17:14:52 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$14(CacheLoader.java:928)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:53 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:mytik_attr
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for mytik_attr
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: bui_mytickets_attrs
INFO - 2021-07-20 17:14:54 [c.c.c.a.helper.bui.BuiConfigHelper] - Start loading Business user interface config properties from DB.
INFO - 2021-07-20 17:14:54 [c.c.c.a.helper.bui.BuiConfigHelper] - Got count ( 0 ) bui_config records
ERROR - 2021-07-20 17:14:54 [c.c.c.a.helper.bui.BuiConfigHelper] - No active records found for object bui_config. Returning -1
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:usp_apps
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for usp_apps
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: usp_apps
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:usp_app_acctyp
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for usp_app_acctyp
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: usp_app_acctyp
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:streams_cache
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for streams_cache
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: streams
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: cr_trans
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: crs
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: transition_type
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: in_trans
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: crs
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: transition_type
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: pr_trans
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: crs
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:notify_list_cache
INFO - 2021-07-20 17:14:54 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for notify_list_cache
ERROR - 2021-07-20 17:14:54 [c.helper.cache.TicketCacheManager] - Exception initializing ticket cache
net.sf.ehcache.CacheException: No such search attribute [contactId] defined for this cache [notify_list_cache]
at net.sf.ehcache.Cache.getSearchAttribute(Cache.java:3900)
at controllers.helper.cache.TicketCacheManager.initContactNotifyListCache(TicketCacheManager.java:1325)
at controllers.helper.cache.TicketCacheManager.init(TicketCacheManager.java:119)
at global.GlobalApplicationSettings.<init>(GlobalApplicationSettings.java:182)
at global.GlobalApplicationSettings$$FastClassByGuice$$66c47645.newInstance(<generated>)
INFO - 2021-07-20 17:14:55 [com.ca.casm.actor.ClusterManager] - LocalHostName is : **********XFT.---FQDN---
INFO - 2021-07-20 17:14:55 [com.ca.casm.actor.ClusterManager] - seedNodesMap: {akka.cluster.seed-nodes.0=akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551}
INFO - 2021-07-20 17:14:55 [com.ca.casm.actor.ClusterManager] - Found nodeKey: akka.cluster.seed-nodes.0 from DB.
INFO - 2021-07-20 17:14:59 [com.ca.casm.actor.ClusterManager] - Added cluster seed node: akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551
INFO - 2021-07-20 17:14:59 [com.ca.casm.actor.ClusterManager] - We got an existing seed node: akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551 and the port is : 2551
INFO - 2021-07-20 17:14:59 [com.ca.casm.actor.ClusterManager] - Seed Nodes are present in DB: {akka.cluster.seed-nodes.0=akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551}
INFO - 2021-07-20 17:14:59 [com.ca.casm.actor.ClusterManager] - Cluster config defined as: {akka.actor.provider:"cluster",akka.remote.netty.tcp.hostname:"**********XFT.---FQDN---",akka.remote.netty.tcp.port:2551,akka.cluster.seed-nodes.0:"akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551",akka.extensions:["akka.cluster.pubsub.DistributedPubSub"]}
INFO - 2021-07-20 17:15:00 [akka.event.slf4j.Slf4jLogger] - Slf4jLogger started
INFO - 2021-07-20 17:15:00 [akka.remote.Remoting] - Starting remoting
INFO - 2021-07-20 17:15:01 [akka.remote.Remoting] - Remoting started; listening on addresses :[akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551]
INFO - 2021-07-20 17:15:01 [akka.remote.Remoting] - Remoting now listens on addresses: [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551]
INFO - 2021-07-20 17:15:01 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Starting up, Akka version [2.5.19] ...
INFO - 2021-07-20 17:15:01 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Registered cluster JMX MBean [akka:type=Cluster]
INFO - 2021-07-20 17:15:01 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Started up successfully
INFO - 2021-07-20 17:15:01 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:authentication-cache
INFO - 2021-07-20 17:15:01 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] is JOINING itself (with roles [dc-default]) and forming new cluster
INFO - 2021-07-20 17:15:01 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] dc [default] is the new leader
INFO - 2021-07-20 17:15:01 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Leader is moving node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] to [Up]
INFO - 2021-07-20 17:15:01 [a.c.s.ClusterSingletonManager] - Singleton manager starting singleton actor [akka://Incident_MicroService_XflowClusterSystem/user/removeIdleSessionsService/singleton]
INFO - 2021-07-20 17:15:01 [a.c.s.ClusterSingletonManager] - ClusterSingletonManager state change [Start -> Oldest]
ERROR - 2021-07-20 17:15:01 [c.c.c.a.s.SessionCleanUpThread] - Invalid value specified for SessionTimeout in conf file
ERROR - 2021-07-20 17:15:01 [c.c.c.a.s.SessionCleanUpThread] - Invalid value specified for PeriodicallyUpdateLastAccessTime in conf file
INFO - 2021-07-20 17:15:01 [c.c.c.a.s.SessionCleanUpThread] - Starting SessionCleanUpThread actor as a singleton...
ERROR - 2021-07-20 17:15:01 [c.c.c.a.s.AuthenticationServiceSessionCache] - Invalid value specified for SessionTimeout in configuration
INFO - 2021-07-20 17:15:02 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Marked address [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] as [Leaving]
INFO - 2021-07-20 17:15:02 [a.c.s.ClusterSingletonManager] - Exited [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551]
INFO - 2021-07-20 17:15:02 [a.c.s.ClusterSingletonManager] - Oldest observed OldestChanged: [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551 -> None]
INFO - 2021-07-20 17:15:02 [a.c.s.ClusterSingletonManager] - ClusterSingletonManager state change [Oldest -> WasOldest]
INFO - 2021-07-20 17:15:03 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Leader is moving node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] to [Exiting]
INFO - 2021-07-20 17:15:04 [a.c.s.ClusterSingletonManager] - Singleton manager starting singleton actor [akka://Incident_MicroService_XflowClusterSystem/user/removeIdleSessionsService/singleton]
INFO - 2021-07-20 17:15:04 [a.c.s.ClusterSingletonManager] - ClusterSingletonManager state change [WasOldest -> Stopping]
INFO - 2021-07-20 17:15:04 [c.c.c.a.s.SessionCleanUpThread] - Stopping SessionCleanUpThread actor...
INFO - 2021-07-20 17:15:04 [a.c.s.ClusterSingletonManager] - Singleton actor [akka://Incident_MicroService_XflowClusterSystem/user/removeIdleSessionsService/singleton] was terminated
INFO - 2021-07-20 17:15:04 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Exiting completed
INFO - 2021-07-20 17:15:04 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Shutting down...
INFO - 2021-07-20 17:15:04 [a.c.Cluster(akka://Incident_MicroService_XflowClusterSystem)] - Cluster Node [akka.tcp://Incident_MicroService_XflowClusterSystem@**********XFT.---FQDN---:2551] - Successfully shut down
INFO - 2021-07-20 17:15:05 [a.r.RemoteActorRefProvider$RemotingTerminator] - Shutting down remote daemon.
INFO - 2021-07-20 17:15:05 [a.r.RemoteActorRefProvider$RemotingTerminator] - Remote daemon shut down; proceeding with flushing remote transports.
INFO - 2021-07-20 17:15:05 [akka.remote.Remoting] - Remoting shut down
INFO - 2021-07-20 17:15:05 [a.r.RemoteActorRefProvider$RemotingTerminator] - Remoting shut down.
xFlow\APPS\logs\incidentMS_slump.log, xFlow\APPS\logs\insightMS_slump.log, xFlow\APPS\logs\searchMS_slump.log
-------------------------------------------------------------------------------------------------------------
07/20 17:14:23.332 [main] INFO Version 30 HV000001: Hibernate Validator 5.4.1.Final
07/20 17:14:23.363 [main] WARN ParameterMessageInterpolator 28 HV000184: ParameterMessageInterpolator has been chosen, EL interpolation will not be supported
07/20 17:14:23.722 [main] INFO NXenv 158 NX_ROOT was not found in the System Environment
07/20 17:14:23.738 [main] INFO NXenv 177 NX_ROOT found in paradigm.ini
07/20 17:14:23.738 [main] INFO NXenv 189 NX_ROOT = C:/PROGRA~2/CA/SERVIC~1
07/20 17:14:23.753 [main] INFO SLUMP 133 Created SLUMP on **********XFT at port 2100
07/20 17:14:23.753 [main] INFO TCP_port 169 Created standard port 2100 for **********XFT with id 1626794063753
07/20 17:14:24.082 [main] INFO HEARTBEAT_SLNX 96 NX_SLUMP_HEARTBEAT_TIMEOUT set to 900 seconds
07/20 17:14:24.488 [TCP_port-Read:Slump_nxd] INFO TCP_port 229 Created fastchannel port 2107 for null with id 1626794064488 between 1511118521.play_service_8168@**********XFT and 2435.domsrvr
07/20 17:14:24.941 [TCP_port-Read:Slump_nxd] INFO TCP_port 229 Created fastchannel port 2106 for null with id 1626794064941 between 1511118521.play_service_8168@**********XFT and 2435.bpvirtdb_srvr
07/20 17:14:47.066 [main] ERROR BPMessage 639 Attempt to getString argument 1 which is a Unknown
xFlow\APPS\logs\incidentservicestderr.txt, xFlow\APPS\logs\insightservicestderr.txt, xFlow\APPS\logs\searchservicestderr.txt
----------------------------------------------------------------------------------------------------------------------------
<empty>
xFlow\APPS\logs\incidentservicestdout.txt, xFlow\APPS\logs\searchservicestdout.txt
------------------------------------------------------------------------------------
<empty>
xFlow\APPS\logs\collabMS.log
----------------------------
INFO - 2021-07-20 17:14:14 [modules.CollaborationModule] - Fired the Collaboration Module Conf.......
INFO - 2021-07-20 17:14:19 [akka.event.slf4j.Slf4jLogger] - Slf4jLogger started
INFO - 2021-07-20 17:14:28 [n.s.e.p.s.f.AnnotationSizeOfFilter] - Using regular expression provided through VM argument net.sf.ehcache.pool.sizeof.ignore.pattern for IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
INFO - 2021-07-20 17:14:29 [n.s.e.pool.sizeof.JvmInformation] - Detected JVM data model settings of: 64-Bit OpenJDK JVM with Compressed OOPs
INFO - 2021-07-20 17:14:29 [n.s.ehcache.pool.sizeof.AgentLoader] - Failed to attach to VM and load the agent: class java.io.IOException: Can not attach to current VM
INFO - 2021-07-20 17:14:29 [n.s.e.pool.impl.DefaultSizeOfEngine] - using Unsafe sizeof engine
INFO - 2021-07-20 17:14:33 [application] - Connecting to Slump
INFO - 2021-07-20 17:14:34 [global.GlobalApplicationSettings] - APP_CODE: 3
INFO - 2021-07-20 17:14:34 [global.GlobalApplicationSettings] - APP_NAME: Collaboration MicroService
INFO - 2021-07-20 17:14:34 [global.GlobalApplicationSettings] - APP_ROOT_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\collabmicroservice-17.0.479\
INFO - 2021-07-20 17:14:34 [global.GlobalApplicationSettings] - APP_LIB_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\collabmicroservice-17.0.479\common\target\scala-2.12\classes;..\common\target\scala-2.12\classes;C:\PROGRA~1\CA\xFlow\APPS\Services\collabmicroservice-17.0.479\lib\
INFO - 2021-07-20 17:14:34 [global.GlobalApplicationSettings] - Generating SDM POJOs to location C:\PROGRA~1\CA\xFlow\APPS\\temp\CasmPlayApi\Source\Pojo\
INFO - 2021-07-20 17:14:34 [com.ca.casm.utils.Utils] - The Service Desk environment is non-tenanted.
INFO - 2021-07-20 17:15:00 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock acquired: true, is shared: false
INFO - 2021-07-20 17:15:09 [com.ca.casm.sdm.SdmPojoProvider] - Skipping POJO generation as majic checksums match!
INFO - 2021-07-20 17:15:10 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock released: true
INFO - 2021-07-20 17:15:12 [global.GlobalApplicationSettings] - Unit Test ---> PriPOJO Entity Name = pri
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:encryptRoleID
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.audienceuris
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.enableManualRedirect
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.realm
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.reply
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.friendlyname
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.issuer
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.thumbprint
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:Incident_MicroService_akka.cluster.seed-nodes.0
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.context
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.domain
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.hostname
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.insightserver.name
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.requestimeout
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.theme
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.protocol
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:sso.secured.cookies
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - KD Settings: SUBMIT_COMMENTS = 1
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - KD Settings: VIEW_COMMENTS = 1
INFO - 2021-07-20 17:15:12 [com.ca.casm.utils.Utils] - log4jPropFilePath: C:\PROGRA~1\CA\xFlow\APPS\Services\collabmicroservice-17.0.479\\conf\log4j.properties
INFO - 2021-07-20 17:15:12 [com.ca.casm.utils.Utils] - log4jRefreshInterval variable is null or empty. Defaulting the refresh interval to 300 seconds.
INFO - 2021-07-20 17:15:12 [c.ca.casm.authorization.CacheLoader] - Started loading cache_info from database and ehcache.xml file.
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cache_info
INFO - 2021-07-20 17:15:12 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cache_info
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from CA SDM):itpam_tasks_info_cache#max#entry:25000
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from xml):user_chat_session_cache#max#entry:2000
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from xml):bot_session#max#entry:10000
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_auth
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_auth
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache related to role authorization.
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for functional access role.
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_access
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - Total role objects : 46
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_access
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:dcon
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - Total func_access_role object count : 1664
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for dcon
ERROR - 2021-07-20 17:15:13 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$12(CacheLoader.java:762)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for data partition.
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp_role
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - Total data partition record count is 178
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp_role
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type role.
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - Total acctyp_role record count is 119
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type objects.
INFO - 2021-07-20 17:15:13 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:my_context
INFO - 2021-07-20 17:15:13 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for my_context
ERROR - 2021-07-20 17:15:13 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$loadAccTypeCache$20(CacheLoader.java:1387)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
ERROR - 2021-07-20 17:15:13 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$16(CacheLoader.java:1106)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
ERROR - 2021-07-20 17:15:14 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$18(CacheLoader.java:1249)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:14 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: object_customized
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: bui_object_customized
INFO - 2021-07-20 17:15:14 [c.ca.casm.authorization.Authorizer] - Register for changes initialization started for authorization module cache.
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: role
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: func_access_role
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: dcon
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tenant
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tenant
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tenant
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tgm_members
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tgm_members
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tgm_members
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cnt
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cnt
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: cnt
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp_role
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: user_context
INFO - 2021-07-20 17:15:14 [c.ca.casm.authorization.Authorizer] - Register for changes finished registration with SDM.
INFO - 2021-07-20 17:15:14 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:master_data_cache
INFO - 2021-07-20 17:15:15 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for master_data_cache
ERROR - 2021-07-20 17:15:15 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$14(CacheLoader.java:928)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:15 [c.c.c.a.cache.MasterDataCacheMgr] - Caching for master data factories is disabled.
INFO - 2021-07-20 17:15:15 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:authentication-cache
ERROR - 2021-07-20 17:15:15 [c.c.c.a.s.AuthenticationServiceSessionCache] - Invalid value specified for SessionTimeout in configuration
xFlow\APPS\logs\collabservicestderr.txt
---------------------------------------
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.google.inject.internal.cglib.core.$ReflectUtils$1 (file:/C:/Program%20Files/CA/xFlow/APPS/Services/collabmicroservice-17.0.479/lib/com.google.inject.guice-4.1.0.jar) to method java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain)
WARNING: Please consider reporting this to the maintainers of com.google.inject.internal.cglib.core.$ReflectUtils$1
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
log4j:ERROR setFile(null,true) call failed.
java.io.FileNotFoundException: =C:\PROGRA~1\CA\xFlow\APPS\logs\collabMS_slump.log (Die Syntax für den Dateinamen, Verzeichnisnamen oder die Datenträgerbezeichnung ist falsch)
at java.base/java.io.FileOutputStream.open0(Native Method)
at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:158)
at org.apache.log4j.FileAppender.setFile(FileAppender.java:290)
at org.apache.log4j.RollingFileAppender.setFile(RollingFileAppender.java:194)
at org.apache.log4j.FileAppender.activateOptions(FileAppender.java:164)
at org.apache.log4j.config.PropertySetter.activate(PropertySetter.java:257)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:133)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:97)
at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:689)
at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:647)
at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:544)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:440)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:476)
at org.apache.log4j.helpers.OptionConverter.selectAndConfigure(OptionConverter.java:471)
at org.apache.log4j.LogManager.<clinit>(LogManager.java:125)
at java.base/java.lang.Class.forName0(Native Method)
at java.base/java.lang.Class.forName(Class.java:398)
at org.jboss.logging.LoggerProviders.tryLog4j(LoggerProviders.java:134)
at org.jboss.logging.LoggerProviders.findProvider(LoggerProviders.java:96)
at org.jboss.logging.LoggerProviders.find(LoggerProviders.java:34)
at org.jboss.logging.LoggerProviders.<clinit>(LoggerProviders.java:31)
at org.jboss.logging.Logger.getLogger(Logger.java:2465)
at org.jboss.logging.Logger$1.run(Logger.java:2565)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at org.jboss.logging.Logger.getMessageLogger(Logger.java:2529)
at org.jboss.logging.Logger.getMessageLogger(Logger.java:2516)
at org.hibernate.validator.internal.util.logging.LoggerFactory.make(LoggerFactory.java:19)
at org.hibernate.validator.internal.util.Version.<clinit>(Version.java:22)
at org.hibernate.validator.internal.engine.ConfigurationImpl.<clinit>(ConfigurationImpl.java:71)
at org.hibernate.validator.HibernateValidator.createGenericConfiguration(HibernateValidator.java:33)
at javax.validation.Validation$GenericBootstrapImpl.configure(Validation.java:276)
at play.data.validation.ValidatorProvider.<init>(ValidatorProvider.java:27)
at play.data.validation.ValidatorProvider$$FastClassByGuice$$580fd498.newInstance(<generated>)
at com.google.inject.internal.DefaultConstructionProxyFactory$FastClassProxy.newInstance(DefaultConstructionProxyFactory.java:89)
at com.google.inject.internal.ConstructorInjector.provision(ConstructorInjector.java:111)
at com.google.inject.internal.ConstructorInjector.construct(ConstructorInjector.java:90)
at com.google.inject.internal.ConstructorBindingImpl$Factory.get(ConstructorBindingImpl.java:268)
at com.google.inject.internal.ProviderToInternalFactoryAdapter$1.call(ProviderToInternalFactoryAdapter.java:46)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1092)
at com.google.inject.internal.ProviderToInternalFactoryAdapter.get(ProviderToInternalFactoryAdapter.java:40)
at com.google.inject.internal.SingletonScope$1.get(SingletonScope.java:194)
at com.google.inject.internal.InternalFactoryToProviderAdapter.get(InternalFactoryToProviderAdapter.java:41)
at com.google.inject.internal.BoundProviderFactory.get(BoundProviderFactory.java:61)
at com.google.inject.internal.SingleParameterInjector.inject(SingleParameterInjector.java:38)
at com.google.inject.internal.SingleParameterInjector.getAll(SingleParameterInjector.java:62)
at com.google.inject.internal.ConstructorInjector.provision(ConstructorInjector.java:110)
at com.google.inject.internal.ConstructorInjector.construct(ConstructorInjector.java:90)
at com.google.inject.internal.ConstructorBindingImpl$Factory.get(ConstructorBindingImpl.java:268)
at com.google.inject.internal.ProviderToInternalFactoryAdapter$1.call(ProviderToInternalFactoryAdapter.java:46)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1092)
at com.google.inject.internal.ProviderToInternalFactoryAdapter.get(ProviderToInternalFactoryAdapter.java:40)
at com.google.inject.internal.SingletonScope$1.get(SingletonScope.java:194)
at com.google.inject.internal.InternalFactoryToProviderAdapter.get(InternalFactoryToProviderAdapter.java:41)
at com.google.inject.internal.InternalInjectorCreator$1.call(InternalInjectorCreator.java:205)
at com.google.inject.internal.InternalInjectorCreator$1.call(InternalInjectorCreator.java:199)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1085)
at com.google.inject.internal.InternalInjectorCreator.loadEagerSingletons(InternalInjectorCreator.java:199)
at com.google.inject.internal.InternalInjectorCreator.injectDynamically(InternalInjectorCreator.java:180)
at com.google.inject.internal.InternalInjectorCreator.build(InternalInjectorCreator.java:110)
at com.google.inject.Guice.createInjector(Guice.java:99)
at com.google.inject.Guice.createInjector(Guice.java:84)
at play.api.inject.guice.GuiceBuilder.injector(GuiceInjectorBuilder.scala:185)
at play.api.inject.guice.GuiceApplicationBuilder.build(GuiceApplicationBuilder.scala:137)
at play.api.inject.guice.GuiceApplicationLoader.load(GuiceApplicationLoader.scala:21)
at play.core.server.ProdServerStart$.start(ProdServerStart.scala:51)
at play.core.server.ProdServerStart$.main(ProdServerStart.scala:25)
at play.core.server.ProdServerStart.main(ProdServerStart.scala)
log4j:ERROR setFile(null,true) call failed.
java.io.FileNotFoundException: =C:\PROGRA~1\CA\xFlow\APPS\logs\collabMS_slump.log (Die Syntax für den Dateinamen, Verzeichnisnamen oder die Datenträgerbezeichnung ist falsch)
at java.base/java.io.FileOutputStream.open0(Native Method)
at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:158)
at org.apache.log4j.FileAppender.setFile(FileAppender.java:290)
at org.apache.log4j.RollingFileAppender.setFile(RollingFileAppender.java:194)
at org.apache.log4j.FileAppender.activateOptions(FileAppender.java:164)
at org.apache.log4j.config.PropertySetter.activate(PropertySetter.java:257)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:133)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:97)
at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:689)
at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:647)
at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:544)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:440)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:334)
xFlow\APPS\logs\collabservicestdout.txt
---------------------------------------
17:14:12,457 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
17:14:12,457 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
17:14:12,457 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Program%20Files/CA/xFlow/APPS/Services/collabmicroservice-17.0.479/conf/logback.xml]
17:14:12,831 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:14:12,863 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Program%20Files/CA/xFlow/APPS/Services/collabmicroservice-17.0.479/conf/logback.xml]
17:14:12,863 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 5 minutes
17:14:12,863 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word coloredLevel with class [play.api.libs.logback.ColoredLevel]
17:14:12,863 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:14:12,878 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:14:12,894 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:79 - no applicable action for [rollingPolicy], current ElementPath is [[configuration][appender][rollingPolicy]]
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@26:26 - no applicable action for [fileNamePattern], current ElementPath is [[configuration][appender][rollingPolicy][fileNamePattern]]
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@27:107 - no applicable action for [timeBasedFileNamingAndTriggeringPolicy], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy]]
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@28:26 - no applicable action for [maxFileSize], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy][maxFileSize]]
17:14:12,972 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@31:21 - no applicable action for [maxHistory], current ElementPath is [[configuration][appender][rollingPolicy][maxHistory]]
17:14:12,972 |-INFO in ch.qos.logback.core.FileAppender[FILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS.log]
17:14:12,972 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:13,316 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [CHATANALYZER]
17:14:13,378 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:13,441 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1717739363 - Archive files will be limited to [30 MB] each.
17:14:13,441 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1717739363 - No compression will be used
17:14:13,441 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1717739363 - Will use the pattern C:/PROGRA~1/CA/xFlow/APPS//logs/user_chat_%d{yyyy-MM-dd}_%i.log for the active file
17:14:13,441 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@841e575 - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/PROGRA~1/CA/xFlow/APPS//logs/user_chat_%d{yyyy-MM-dd}_%i.log'.
17:14:13,441 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@841e575 - Roll-over at midnight.
17:14:13,472 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@841e575 - Setting initial period to Tue Jul 20 11:12:51 CEST 2021
17:14:13,472 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[CHATANALYZER] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/user_chat.log
17:14:13,472 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[CHATANALYZER] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/user_chat.log]
17:14:13,472 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNCFILE]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to ch.qos.logback.classic.AsyncAppender[ASYNCFILE]
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Attaching appender named [FILE] to AsyncAppender.
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Setting discardingThreshold to 51
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNC_CHAT_ANALYZER]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [CHATANALYZER] to ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER]
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Attaching appender named [CHATANALYZER] to AsyncAppender.
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Setting discardingThreshold to 51
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DEBUGFILE]
17:14:13,503 |-INFO in ch.qos.logback.core.rolling.FixedWindowRollingPolicy@27a5328c - No compression will be used
17:14:13,503 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS_debug.log
17:14:13,503 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS_debug.log]
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [play] to INFO
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [application] to INFO
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ChatAnalyzer] to INFO
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ChatAnalyzer] to false
17:14:13,503 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNC_CHAT_ANALYZER] to Logger[ChatAnalyzer]
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebean.config.PropertyMapLoader] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.core.XmlConfigLoader] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.lib.BackgroundThread] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.gargoylesoftware.htmlunit.javascript] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
17:14:13,503 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNCFILE] to Logger[ROOT]
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@1e5f4170 - Registering current configuration as safe fallback point
17:14:12,457 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
17:14:12,457 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
17:14:12,457 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Program%20Files/CA/xFlow/APPS/Services/collabmicroservice-17.0.479/conf/logback.xml]
17:14:12,831 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:14:12,863 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Program%20Files/CA/xFlow/APPS/Services/collabmicroservice-17.0.479/conf/logback.xml]
17:14:12,863 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 5 minutes
17:14:12,863 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word coloredLevel with class [play.api.libs.logback.ColoredLevel]
17:14:12,863 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:14:12,878 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:14:12,894 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:79 - no applicable action for [rollingPolicy], current ElementPath is [[configuration][appender][rollingPolicy]]
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@26:26 - no applicable action for [fileNamePattern], current ElementPath is [[configuration][appender][rollingPolicy][fileNamePattern]]
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@27:107 - no applicable action for [timeBasedFileNamingAndTriggeringPolicy], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy]]
17:14:12,956 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@28:26 - no applicable action for [maxFileSize], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy][maxFileSize]]
17:14:12,972 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@31:21 - no applicable action for [maxHistory], current ElementPath is [[configuration][appender][rollingPolicy][maxHistory]]
17:14:12,972 |-INFO in ch.qos.logback.core.FileAppender[FILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS.log]
17:14:12,972 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:13,316 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [CHATANALYZER]
17:14:13,378 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:13,441 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1717739363 - Archive files will be limited to [30 MB] each.
17:14:13,441 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1717739363 - No compression will be used
17:14:13,441 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1717739363 - Will use the pattern C:/PROGRA~1/CA/xFlow/APPS//logs/user_chat_%d{yyyy-MM-dd}_%i.log for the active file
17:14:13,441 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@841e575 - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/PROGRA~1/CA/xFlow/APPS//logs/user_chat_%d{yyyy-MM-dd}_%i.log'.
17:14:13,441 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@841e575 - Roll-over at midnight.
17:14:13,472 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@841e575 - Setting initial period to Tue Jul 20 11:12:51 CEST 2021
17:14:13,472 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[CHATANALYZER] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/user_chat.log
17:14:13,472 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[CHATANALYZER] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/user_chat.log]
17:14:13,472 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNCFILE]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to ch.qos.logback.classic.AsyncAppender[ASYNCFILE]
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Attaching appender named [FILE] to AsyncAppender.
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Setting discardingThreshold to 51
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNC_CHAT_ANALYZER]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [CHATANALYZER] to ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER]
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Attaching appender named [CHATANALYZER] to AsyncAppender.
17:14:13,488 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Setting discardingThreshold to 51
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:13,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DEBUGFILE]
17:14:13,503 |-INFO in ch.qos.logback.core.rolling.FixedWindowRollingPolicy@27a5328c - No compression will be used
17:14:13,503 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS_debug.log
17:14:13,503 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS_debug.log]
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [play] to INFO
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [application] to INFO
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ChatAnalyzer] to INFO
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ChatAnalyzer] to false
17:14:13,503 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNC_CHAT_ANALYZER] to Logger[ChatAnalyzer]
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebean.config.PropertyMapLoader] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.core.XmlConfigLoader] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.lib.BackgroundThread] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.gargoylesoftware.htmlunit.javascript] to OFF
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
17:14:13,503 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNCFILE] to Logger[ROOT]
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
17:14:13,503 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@1e5f4170 - Registering current configuration as safe fallback point
17:14:13,644 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Worker thread will flush remaining events before exiting.
17:14:13,910 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Queue flush finished successfully within timeout.
17:14:13,910 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Worker thread will flush remaining events before exiting.
17:14:13,910 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Queue flush finished successfully within timeout.
17:14:13,925 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:14:13,925 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Program%20Files/CA/xFlow/APPS/Services/collabmicroservice-17.0.479/conf/logback.xml]
17:14:13,925 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 5 minutes
17:14:14,160 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word coloredLevel with class [play.api.libs.logback.ColoredLevel]
17:14:14,160 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:14:14,160 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:14:14,160 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:14,160 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:79 - no applicable action for [rollingPolicy], current ElementPath is [[configuration][appender][rollingPolicy]]
17:14:14,160 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@26:26 - no applicable action for [fileNamePattern], current ElementPath is [[configuration][appender][rollingPolicy][fileNamePattern]]
17:14:14,160 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@27:107 - no applicable action for [timeBasedFileNamingAndTriggeringPolicy], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy]]
17:14:14,160 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@28:26 - no applicable action for [maxFileSize], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy][maxFileSize]]
17:14:14,160 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@31:21 - no applicable action for [maxHistory], current ElementPath is [[configuration][appender][rollingPolicy][maxHistory]]
17:14:14,160 |-INFO in ch.qos.logback.core.FileAppender[FILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS.log]
17:14:14,160 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:14,160 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [CHATANALYZER]
17:14:14,160 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:14,160 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@2031927175 - Archive files will be limited to [30 MB] each.
17:14:14,160 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@2031927175 - No compression will be used
17:14:14,160 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@2031927175 - Will use the pattern C:/PROGRA~1/CA/xFlow/APPS//logs/user_chat_%d{yyyy-MM-dd}_%i.log for the active file
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@a7e2d9d - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/PROGRA~1/CA/xFlow/APPS//logs/user_chat_%d{yyyy-MM-dd}_%i.log'.
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@a7e2d9d - Roll-over at midnight.
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@a7e2d9d - Setting initial period to Tue Jul 20 11:12:51 CEST 2021
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[CHATANALYZER] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/user_chat.log
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[CHATANALYZER] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/user_chat.log]
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNCFILE]
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to ch.qos.logback.classic.AsyncAppender[ASYNCFILE]
17:14:14,175 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Attaching appender named [FILE] to AsyncAppender.
17:14:14,175 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Setting discardingThreshold to 51
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNC_CHAT_ANALYZER]
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [CHATANALYZER] to ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER]
17:14:14,175 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Attaching appender named [CHATANALYZER] to AsyncAppender.
17:14:14,175 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNC_CHAT_ANALYZER] - Setting discardingThreshold to 51
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:14,175 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DEBUGFILE]
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.FixedWindowRollingPolicy@754777cd - No compression will be used
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS_debug.log
17:14:14,175 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/collabMS_debug.log]
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [play] to INFO
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [application] to INFO
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ChatAnalyzer] to INFO
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ChatAnalyzer] to false
17:14:14,191 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNC_CHAT_ANALYZER] to Logger[ChatAnalyzer]
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebean.config.PropertyMapLoader] to OFF
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.core.XmlConfigLoader] to OFF
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.lib.BackgroundThread] to OFF
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.gargoylesoftware.htmlunit.javascript] to OFF
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
17:14:14,191 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNCFILE] to Logger[ROOT]
17:14:14,191 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
xFlow\APPS\logs\insightMS.log
-----------------------------
INFO - 2021-07-20 17:14:38 [modules.InsightMSModule] - Reading the Module Configurations.......
INFO - 2021-07-20 17:14:41 [akka.event.slf4j.Slf4jLogger] - Slf4jLogger started
INFO - 2021-07-20 17:14:47 [application] - Connecting to Slump
INFO - 2021-07-20 17:14:47 [global.GlobalApplicationSettings] - APP_CODE: 4
INFO - 2021-07-20 17:14:47 [global.GlobalApplicationSettings] - APP_NAME: Insight MicroService
INFO - 2021-07-20 17:14:47 [global.GlobalApplicationSettings] - APP_ROOT_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\insightmicroservice-17.1.706\
INFO - 2021-07-20 17:14:47 [global.GlobalApplicationSettings] - APP_LIB_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\insightmicroservice-17.1.706\common\target\scala-2.12\classes;..\common\target\scala-2.12\classes;C:\PROGRA~1\CA\xFlow\APPS\Services\insightmicroservice-17.1.706\lib\
INFO - 2021-07-20 17:14:47 [global.GlobalApplicationSettings] - Generating SDM POJOs to location C:\PROGRA~1\CA\xFlow\APPS\\temp\CasmPlayApi\Source\Pojo\
INFO - 2021-07-20 17:14:47 [com.ca.casm.utils.Utils] - The Service Desk environment is non-tenanted.
INFO - 2021-07-20 17:15:09 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock acquired: true, is shared: false
INFO - 2021-07-20 17:15:16 [com.ca.casm.sdm.SdmPojoProvider] - Skipping POJO generation as majic checksums match!
INFO - 2021-07-20 17:15:17 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock released: true
INFO - 2021-07-20 17:15:18 [global.GlobalApplicationSettings] - Unit Test ---> PriPOJO Entity Name = pri
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:encryptRoleID
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.audienceuris
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.enableManualRedirect
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.realm
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.reply
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.friendlyname
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.issuer
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.thumbprint
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:Incident_MicroService_akka.cluster.seed-nodes.0
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.context
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.domain
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.hostname
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.insightserver.name
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.requestimeout
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.theme
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.protocol
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:sso.secured.cookies
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - KD Settings: SUBMIT_COMMENTS = 1
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - KD Settings: VIEW_COMMENTS = 1
INFO - 2021-07-20 17:15:18 [com.ca.casm.utils.Utils] - log4jPropFilePath: C:\PROGRA~1\CA\xFlow\APPS\Services\insightmicroservice-17.1.706\\conf\log4j.properties
INFO - 2021-07-20 17:15:18 [com.ca.casm.utils.Utils] - log4jRefreshInterval variable is null or empty. Defaulting the refresh interval to 300 seconds.
INFO - 2021-07-20 17:15:18 [c.ca.casm.authorization.CacheLoader] - Started loading cache_info from database and ehcache.xml file.
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cache_info
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cache_info
INFO - 2021-07-20 17:15:18 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from CA SDM):itpam_tasks_info_cache#max#entry:25000
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_auth
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_auth
INFO - 2021-07-20 17:15:18 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache related to role authorization.
INFO - 2021-07-20 17:15:18 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for functional access role.
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_access
INFO - 2021-07-20 17:15:18 [c.ca.casm.authorization.CacheLoader] - Total role objects : 46
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_access
INFO - 2021-07-20 17:15:18 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:dcon
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - Total func_access_role object count : 1664
ERROR - 2021-07-20 17:15:19 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$12(CacheLoader.java:762)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for dcon
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for data partition.
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp_role
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - Total data partition record count is 178
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp_role
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type role.
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - Total acctyp_role record count is 119
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type objects.
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:my_context
ERROR - 2021-07-20 17:15:19 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$16(CacheLoader.java:1106)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
ERROR - 2021-07-20 17:15:19 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$18(CacheLoader.java:1249)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for my_context
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: object_customized
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: bui_object_customized
INFO - 2021-07-20 17:15:19 [c.ca.casm.authorization.Authorizer] - Register for changes initialization started for authorization module cache.
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: role
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: func_access_role
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: dcon
INFO - 2021-07-20 17:15:19 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tenant
ERROR - 2021-07-20 17:15:20 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$loadAccTypeCache$20(CacheLoader.java:1387)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tenant
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tenant
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tgm_members
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tgm_members
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tgm_members
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cnt
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cnt
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: cnt
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp_role
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: user_context
INFO - 2021-07-20 17:15:20 [c.ca.casm.authorization.Authorizer] - Register for changes finished registration with SDM.
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:master_data_cache
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for master_data_cache
INFO - 2021-07-20 17:15:20 [c.c.c.a.cache.MasterDataCacheMgr] - Caching for master data factories is disabled.
INFO - 2021-07-20 17:15:20 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:authentication-cache
ERROR - 2021-07-20 17:15:20 [c.c.c.a.s.AuthenticationServiceSessionCache] - Invalid value specified for SessionTimeout in configuration
xFlow\APPS\logs\insightservicestdout.txt
-----------------------------------------
17:14:37,410 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
17:14:37,410 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
17:14:37,410 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Program%20Files/CA/xFlow/APPS/Services/insightmicroservice-17.1.706/conf/logback.xml]
17:14:37,722 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:14:37,738 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Program%20Files/CA/xFlow/APPS/Services/insightmicroservice-17.1.706/conf/logback.xml]
17:14:37,738 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 5 minutes
17:14:37,754 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word coloredLevel with class [play.api.libs.logback.ColoredLevel]
17:14:37,754 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:14:37,754 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:14:37,769 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:79 - no applicable action for [rollingPolicy], current ElementPath is [[configuration][appender][rollingPolicy]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@26:26 - no applicable action for [fileNamePattern], current ElementPath is [[configuration][appender][rollingPolicy][fileNamePattern]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@27:107 - no applicable action for [timeBasedFileNamingAndTriggeringPolicy], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@28:26 - no applicable action for [maxFileSize], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy][maxFileSize]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@31:21 - no applicable action for [maxHistory], current ElementPath is [[configuration][appender][rollingPolicy][maxHistory]]
17:14:37,816 |-INFO in ch.qos.logback.core.FileAppender[FILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/insightMS.log]
17:14:37,816 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:37,816 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNCFILE]
17:14:37,816 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to ch.qos.logback.classic.AsyncAppender[ASYNCFILE]
17:14:37,816 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Attaching appender named [FILE] to AsyncAppender.
17:14:37,816 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Setting discardingThreshold to 51
17:14:37,816 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:37,816 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DEBUGFILE]
17:14:37,832 |-INFO in ch.qos.logback.core.rolling.FixedWindowRollingPolicy@6273c5a4 - No compression will be used
17:14:37,832 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/insightMS_debug.log
17:14:37,832 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/insightMS_debug.log]
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [play] to INFO
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [application] to INFO
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebean.config.PropertyMapLoader] to OFF
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.core.XmlConfigLoader] to OFF
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.lib.BackgroundThread] to OFF
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.gargoylesoftware.htmlunit.javascript] to OFF
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
17:14:37,832 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNCFILE] to Logger[ROOT]
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
17:14:37,832 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@5d465e4b - Registering current configuration as safe fallback point
17:14:37,410 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
17:14:37,410 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
17:14:37,410 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Program%20Files/CA/xFlow/APPS/Services/insightmicroservice-17.1.706/conf/logback.xml]
17:14:37,722 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:14:37,738 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Program%20Files/CA/xFlow/APPS/Services/insightmicroservice-17.1.706/conf/logback.xml]
17:14:37,738 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 5 minutes
17:14:37,754 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word coloredLevel with class [play.api.libs.logback.ColoredLevel]
17:14:37,754 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:14:37,754 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:14:37,769 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:79 - no applicable action for [rollingPolicy], current ElementPath is [[configuration][appender][rollingPolicy]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@26:26 - no applicable action for [fileNamePattern], current ElementPath is [[configuration][appender][rollingPolicy][fileNamePattern]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@27:107 - no applicable action for [timeBasedFileNamingAndTriggeringPolicy], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@28:26 - no applicable action for [maxFileSize], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy][maxFileSize]]
17:14:37,816 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@31:21 - no applicable action for [maxHistory], current ElementPath is [[configuration][appender][rollingPolicy][maxHistory]]
17:14:37,816 |-INFO in ch.qos.logback.core.FileAppender[FILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/insightMS.log]
xFlow\APPS\logs\pushMS.log
----------------------------
INFO - 2021-07-20 17:14:24 [n.s.e.p.s.f.AnnotationSizeOfFilter] - Using regular expression provided through VM argument net.sf.ehcache.pool.sizeof.ignore.pattern for IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
INFO - 2021-07-20 17:14:24 [n.s.e.pool.sizeof.JvmInformation] - Detected JVM data model settings of: 64-Bit OpenJDK JVM with Compressed OOPs
INFO - 2021-07-20 17:14:25 [n.s.ehcache.pool.sizeof.AgentLoader] - Failed to attach to VM and load the agent: class java.io.IOException: Can not attach to current VM
INFO - 2021-07-20 17:14:25 [n.s.e.pool.impl.DefaultSizeOfEngine] - using Unsafe sizeof engine
INFO - 2021-07-20 17:14:25 [n.s.e.pool.impl.DefaultSizeOfEngine] - using Unsafe sizeof engine
INFO - 2021-07-20 17:14:28 [akka.event.slf4j.Slf4jLogger] - Slf4jLogger started
INFO - 2021-07-20 17:14:31 [application] - Connecting to Slump
INFO - 2021-07-20 17:14:33 [global.GlobalApplicationSettings] - APP_CODE: 2
INFO - 2021-07-20 17:14:33 [global.GlobalApplicationSettings] - APP_NAME: Push MicroService
INFO - 2021-07-20 17:14:33 [global.GlobalApplicationSettings] - APP_ROOT_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\pushmicroservice-17.0.479\
INFO - 2021-07-20 17:14:33 [global.GlobalApplicationSettings] - APP_LIB_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\pushmicroservice-17.0.479\common\target\scala-2.12\classes;..\common\target\scala-2.12\classes;C:\PROGRA~1\CA\xFlow\APPS\Services\pushmicroservice-17.0.479\lib\
INFO - 2021-07-20 17:14:33 [global.GlobalApplicationSettings] - Generating SDM POJOs to location C:\PROGRA~1\CA\xFlow\APPS\\temp\CasmPlayApi\Source\Pojo\
INFO - 2021-07-20 17:14:33 [com.ca.casm.utils.Utils] - The Service Desk environment is non-tenanted.
INFO - 2021-07-20 17:14:44 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock acquired: true, is shared: false
INFO - 2021-07-20 17:15:00 [com.ca.casm.sdm.SdmPojoProvider] - Skipping POJO generation as majic checksums match!
INFO - 2021-07-20 17:15:03 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock released: true
INFO - 2021-07-20 17:15:05 [global.GlobalApplicationSettings] - Unit Test ---> PriPOJO Entity Name = pri
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:encryptRoleID
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.audienceuris
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.enableManualRedirect
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.realm
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.reply
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.friendlyname
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.issuer
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.thumbprint
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:Incident_MicroService_akka.cluster.seed-nodes.0
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.context
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.domain
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.hostname
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.insightserver.name
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.requestimeout
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.theme
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.protocol
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:sso.secured.cookies
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - KD Settings: SUBMIT_COMMENTS = 1
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - KD Settings: VIEW_COMMENTS = 1
INFO - 2021-07-20 17:15:05 [com.ca.casm.utils.Utils] - log4jPropFilePath: C:\PROGRA~1\CA\xFlow\APPS\Services\pushmicroservice-17.0.479\\conf\log4j.properties
INFO - 2021-07-20 17:15:05 [com.ca.casm.utils.Utils] - log4jRefreshInterval variable is null or empty. Defaulting the refresh interval to 300 seconds.
INFO - 2021-07-20 17:15:05 [c.ca.casm.authorization.CacheLoader] - Started loading cache_info from database and ehcache.xml file.
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cache_info
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cache_info
INFO - 2021-07-20 17:15:05 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from CA SDM):itpam_tasks_info_cache#max#entry:25000
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_auth
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_auth
INFO - 2021-07-20 17:15:05 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache related to role authorization.
INFO - 2021-07-20 17:15:05 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for functional access role.
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_access
INFO - 2021-07-20 17:15:05 [c.ca.casm.authorization.CacheLoader] - Total role objects : 46
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_access
INFO - 2021-07-20 17:15:05 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:dcon
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - Total func_access_role object count : 1664
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for dcon
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for data partition.
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp_role
ERROR - 2021-07-20 17:15:06 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$12(CacheLoader.java:762)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - Total data partition record count is 178
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp_role
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type role.
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - Total acctyp_role record count is 119
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type objects.
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:my_context
ERROR - 2021-07-20 17:15:06 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$16(CacheLoader.java:1106)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for my_context
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: object_customized
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: bui_object_customized
INFO - 2021-07-20 17:15:06 [c.ca.casm.authorization.Authorizer] - Register for changes initialization started for authorization module cache.
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: role
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: func_access_role
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: dcon
INFO - 2021-07-20 17:15:06 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tenant
ERROR - 2021-07-20 17:15:06 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$18(CacheLoader.java:1249)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
ERROR - 2021-07-20 17:15:06 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$loadAccTypeCache$20(CacheLoader.java:1387)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tenant
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tenant
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tgm_members
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tgm_members
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tgm_members
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cnt
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cnt
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: cnt
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp_role
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: user_context
INFO - 2021-07-20 17:15:07 [c.ca.casm.authorization.Authorizer] - Register for changes finished registration with SDM.
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:master_data_cache
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for master_data_cache
INFO - 2021-07-20 17:15:07 [c.c.c.a.cache.MasterDataCacheMgr] - Caching for master data factories is disabled.
INFO - 2021-07-20 17:15:07 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:authentication-cache
ERROR - 2021-07-20 17:15:07 [c.c.c.a.s.AuthenticationServiceSessionCache] - Invalid value specified for SessionTimeout in configuration
ERROR - 2021-07-20 17:15:08 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$14(CacheLoader.java:928)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
xFlow\APPS\logs\pushservicestderr.txt
-------------------------------------
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.google.inject.internal.cglib.core.$ReflectUtils$1 (file:/C:/Program%20Files/CA/xFlow/APPS/Services/pushmicroservice-17.0.479/lib/com.google.inject.guice-4.1.0.jar) to method java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain)
WARNING: Please consider reporting this to the maintainers of com.google.inject.internal.cglib.core.$ReflectUtils$1
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
log4j:ERROR setFile(null,true) call failed.
java.io.FileNotFoundException: =C:\PROGRA~1\CA\xFlow\APPS\logs\pushMS_slump.log (Die Syntax für den Dateinamen, Verzeichnisnamen oder die Datenträgerbezeichnung ist falsch)
at java.base/java.io.FileOutputStream.open0(Native Method)
at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:158)
at org.apache.log4j.FileAppender.setFile(FileAppender.java:290)
at org.apache.log4j.RollingFileAppender.setFile(RollingFileAppender.java:194)
at org.apache.log4j.FileAppender.activateOptions(FileAppender.java:164)
at org.apache.log4j.config.PropertySetter.activate(PropertySetter.java:257)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:133)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:97)
at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:689)
at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:647)
at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:544)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:440)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:476)
at org.apache.log4j.helpers.OptionConverter.selectAndConfigure(OptionConverter.java:471)
at org.apache.log4j.LogManager.<clinit>(LogManager.java:125)
at java.base/java.lang.Class.forName0(Native Method)
at java.base/java.lang.Class.forName(Class.java:398)
at org.jboss.logging.LoggerProviders.tryLog4j(LoggerProviders.java:134)
at org.jboss.logging.LoggerProviders.findProvider(LoggerProviders.java:96)
at org.jboss.logging.LoggerProviders.find(LoggerProviders.java:34)
at org.jboss.logging.LoggerProviders.<clinit>(LoggerProviders.java:31)
at org.jboss.logging.Logger.getLogger(Logger.java:2465)
at org.jboss.logging.Logger$1.run(Logger.java:2565)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at org.jboss.logging.Logger.getMessageLogger(Logger.java:2529)
at org.jboss.logging.Logger.getMessageLogger(Logger.java:2516)
at org.hibernate.validator.internal.util.logging.LoggerFactory.make(LoggerFactory.java:19)
at org.hibernate.validator.internal.util.Version.<clinit>(Version.java:22)
at org.hibernate.validator.internal.engine.ConfigurationImpl.<clinit>(ConfigurationImpl.java:71)
at org.hibernate.validator.HibernateValidator.createGenericConfiguration(HibernateValidator.java:33)
at javax.validation.Validation$GenericBootstrapImpl.configure(Validation.java:276)
at play.data.validation.ValidatorProvider.<init>(ValidatorProvider.java:27)
at play.data.validation.ValidatorProvider$$FastClassByGuice$$580fd498.newInstance(<generated>)
at com.google.inject.internal.DefaultConstructionProxyFactory$FastClassProxy.newInstance(DefaultConstructionProxyFactory.java:89)
at com.google.inject.internal.ConstructorInjector.provision(ConstructorInjector.java:111)
at com.google.inject.internal.ConstructorInjector.construct(ConstructorInjector.java:90)
at com.google.inject.internal.ConstructorBindingImpl$Factory.get(ConstructorBindingImpl.java:268)
at com.google.inject.internal.ProviderToInternalFactoryAdapter$1.call(ProviderToInternalFactoryAdapter.java:46)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1092)
at com.google.inject.internal.ProviderToInternalFactoryAdapter.get(ProviderToInternalFactoryAdapter.java:40)
at com.google.inject.internal.SingletonScope$1.get(SingletonScope.java:194)
at com.google.inject.internal.InternalFactoryToProviderAdapter.get(InternalFactoryToProviderAdapter.java:41)
at com.google.inject.internal.BoundProviderFactory.get(BoundProviderFactory.java:61)
at com.google.inject.internal.SingleParameterInjector.inject(SingleParameterInjector.java:38)
at com.google.inject.internal.SingleParameterInjector.getAll(SingleParameterInjector.java:62)
at com.google.inject.internal.ConstructorInjector.provision(ConstructorInjector.java:110)
at com.google.inject.internal.ConstructorInjector.construct(ConstructorInjector.java:90)
at com.google.inject.internal.ConstructorBindingImpl$Factory.get(ConstructorBindingImpl.java:268)
at com.google.inject.internal.ProviderToInternalFactoryAdapter$1.call(ProviderToInternalFactoryAdapter.java:46)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1092)
at com.google.inject.internal.ProviderToInternalFactoryAdapter.get(ProviderToInternalFactoryAdapter.java:40)
at com.google.inject.internal.SingletonScope$1.get(SingletonScope.java:194)
at com.google.inject.internal.InternalFactoryToProviderAdapter.get(InternalFactoryToProviderAdapter.java:41)
at com.google.inject.internal.InternalInjectorCreator$1.call(InternalInjectorCreator.java:205)
at com.google.inject.internal.InternalInjectorCreator$1.call(InternalInjectorCreator.java:199)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1085)
at com.google.inject.internal.InternalInjectorCreator.loadEagerSingletons(InternalInjectorCreator.java:199)
at com.google.inject.internal.InternalInjectorCreator.injectDynamically(InternalInjectorCreator.java:180)
at com.google.inject.internal.InternalInjectorCreator.build(InternalInjectorCreator.java:110)
at com.google.inject.Guice.createInjector(Guice.java:99)
at com.google.inject.Guice.createInjector(Guice.java:84)
at play.api.inject.guice.GuiceBuilder.injector(GuiceInjectorBuilder.scala:185)
at play.api.inject.guice.GuiceApplicationBuilder.build(GuiceApplicationBuilder.scala:137)
at play.api.inject.guice.GuiceApplicationLoader.load(GuiceApplicationLoader.scala:21)
at play.core.server.ProdServerStart$.start(ProdServerStart.scala:51)
at play.core.server.ProdServerStart$.main(ProdServerStart.scala:25)
at play.core.server.ProdServerStart.main(ProdServerStart.scala)
log4j:ERROR setFile(null,true) call failed.
java.io.FileNotFoundException: =C:\PROGRA~1\CA\xFlow\APPS\logs\pushMS_slump.log (Die Syntax für den Dateinamen, Verzeichnisnamen oder die Datenträgerbezeichnung ist falsch)
at java.base/java.io.FileOutputStream.open0(Native Method)
at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:158)
at org.apache.log4j.FileAppender.setFile(FileAppender.java:290)
at org.apache.log4j.RollingFileAppender.setFile(RollingFileAppender.java:194)
at org.apache.log4j.FileAppender.activateOptions(FileAppender.java:164)
at org.apache.log4j.config.PropertySetter.activate(PropertySetter.java:257)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:133)
at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:97)
at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:689)
at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:647)
at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:544)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:440)
at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:334)
xFlow\APPS\logs\pushservicestdout.txt
-------------------------------------
17:14:14,019 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
17:14:14,019 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
17:14:14,019 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Program%20Files/CA/xFlow/APPS/Services/pushmicroservice-17.0.479/conf/logback.xml]
17:14:14,331 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:14:14,347 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Program%20Files/CA/xFlow/APPS/Services/pushmicroservice-17.0.479/conf/logback.xml]
17:14:14,347 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 5 minutes
17:14:14,363 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word coloredLevel with class [play.api.libs.logback.ColoredLevel]
17:14:14,363 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:14:14,363 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:14:14,378 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:79 - no applicable action for [rollingPolicy], current ElementPath is [[configuration][appender][rollingPolicy]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@26:26 - no applicable action for [fileNamePattern], current ElementPath is [[configuration][appender][rollingPolicy][fileNamePattern]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@27:107 - no applicable action for [timeBasedFileNamingAndTriggeringPolicy], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@28:26 - no applicable action for [maxFileSize], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy][maxFileSize]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@31:21 - no applicable action for [maxHistory], current ElementPath is [[configuration][appender][rollingPolicy][maxHistory]]
17:14:14,441 |-INFO in ch.qos.logback.core.FileAppender[FILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/pushMS.log]
17:14:14,456 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
17:14:14,456 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ASYNCFILE]
17:14:14,456 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to ch.qos.logback.classic.AsyncAppender[ASYNCFILE]
17:14:14,456 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Attaching appender named [FILE] to AsyncAppender.
17:14:14,456 |-INFO in ch.qos.logback.classic.AsyncAppender[ASYNCFILE] - Setting discardingThreshold to 51
17:14:14,456 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:14:14,456 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DEBUGFILE]
17:14:14,456 |-INFO in ch.qos.logback.core.rolling.FixedWindowRollingPolicy@7b139eab - No compression will be used
17:14:14,472 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - Active log file name: C:\PROGRA~1\CA\xFlow\APPS\\logs/pushMS_debug.log
17:14:14,472 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[DEBUGFILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/pushMS_debug.log]
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [play] to INFO
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [application] to INFO
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebean.config.PropertyMapLoader] to OFF
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.core.XmlConfigLoader] to OFF
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.avaje.ebeaninternal.server.lib.BackgroundThread] to OFF
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.gargoylesoftware.htmlunit.javascript] to OFF
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
17:14:14,472 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ASYNCFILE] to Logger[ROOT]
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
17:14:14,472 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@4e76dac - Registering current configuration as safe fallback point
17:14:14,019 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
17:14:14,019 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
17:14:14,019 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Program%20Files/CA/xFlow/APPS/Services/pushmicroservice-17.0.479/conf/logback.xml]
17:14:14,331 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:14:14,347 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Program%20Files/CA/xFlow/APPS/Services/pushmicroservice-17.0.479/conf/logback.xml]
17:14:14,347 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 5 minutes
17:14:14,363 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word coloredLevel with class [play.api.libs.logback.ColoredLevel]
17:14:14,363 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:14:14,363 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:14:14,378 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:79 - no applicable action for [rollingPolicy], current ElementPath is [[configuration][appender][rollingPolicy]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@26:26 - no applicable action for [fileNamePattern], current ElementPath is [[configuration][appender][rollingPolicy][fileNamePattern]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@27:107 - no applicable action for [timeBasedFileNamingAndTriggeringPolicy], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@28:26 - no applicable action for [maxFileSize], current ElementPath is [[configuration][appender][rollingPolicy][timeBasedFileNamingAndTriggeringPolicy][maxFileSize]]
17:14:14,441 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@31:21 - no applicable action for [maxHistory], current ElementPath is [[configuration][appender][rollingPolicy][maxHistory]]
17:14:14,441 |-INFO in ch.qos.logback.core.FileAppender[FILE] - File property is set to [C:\PROGRA~1\CA\xFlow\APPS\\logs/pushMS.log]
17:14:14,456 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
xFlow\APPS\logs\searchMS.log
-------------------------------------
INFO - 2021-07-20 17:14:23 [akka.event.slf4j.Slf4jLogger] - Slf4jLogger started
INFO - 2021-07-20 17:14:28 [application] - Connecting to Slump
INFO - 2021-07-20 17:14:32 [global.GlobalApplicationSettings] - APP_CODE: 1
INFO - 2021-07-20 17:14:32 [global.GlobalApplicationSettings] - APP_NAME: Search MicroService
INFO - 2021-07-20 17:14:32 [global.GlobalApplicationSettings] - APP_ROOT_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\searchmicroservice-17.0.479\
INFO - 2021-07-20 17:14:32 [global.GlobalApplicationSettings] - APP_LIB_DIR: C:\PROGRA~1\CA\xFlow\APPS\Services\searchmicroservice-17.0.479\common\target\scala-2.12\classes;..\common\target\scala-2.12\classes;C:\PROGRA~1\CA\xFlow\APPS\Services\searchmicroservice-17.0.479\lib\
INFO - 2021-07-20 17:14:32 [com.ca.casm.utils.Utils] - The Service Desk environment is non-tenanted.
INFO - 2021-07-20 17:14:35 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock acquired: true, is shared: false
INFO - 2021-07-20 17:14:44 [com.ca.casm.sdm.SdmPojoProvider] - Skipping POJO generation as majic checksums match!
INFO - 2021-07-20 17:14:55 [com.ca.casm.sdm.SdmPojoProvider] - POJO file lock released: true
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:encryptRoleID
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.audienceuris
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.enableManualRedirect
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.realm
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.reply
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.friendlyname
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.issuer
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:federation.trustedissuers.thumbprint
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:Incident_MicroService_akka.cluster.seed-nodes.0
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.context
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.domain
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.hostname
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.insightserver.name
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.requestimeout
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.jasper.theme
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:insights.protocol
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - Property loaded from DB:sso.secured.cookies
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - KD Settings: SUBMIT_COMMENTS = 1
INFO - 2021-07-20 17:14:56 [com.ca.casm.actor.global.ActorUtils] - KD Settings: VIEW_COMMENTS = 1
INFO - 2021-07-20 17:14:56 [com.ca.casm.utils.Utils] - log4jPropFilePath: C:\PROGRA~1\CA\xFlow\APPS\Services\searchmicroservice-17.0.479\\conf\log4j.properties
INFO - 2021-07-20 17:14:56 [com.ca.casm.utils.Utils] - log4jRefreshInterval variable is null or empty. Defaulting the refresh interval to 300 seconds.
INFO - 2021-07-20 17:14:56 [c.ca.casm.authorization.CacheLoader] - Started loading cache_info from database and ehcache.xml file.
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cache_info
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cache_info
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from CA SDM):itpam_tasks_info_cache#max#entry:25000
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - Max entry key-value(from xml):my_context#max#entry:1
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_auth
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_auth
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache related to role authorization.
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for functional access role.
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:role_access
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - Total role objects : 46
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for role_access
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:dcon
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - Total func_access_role object count : 1664
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for dcon
ERROR - 2021-07-20 17:14:57 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$12(CacheLoader.java:762)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for data partition.
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp_role
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - Total data partition record count is 178
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp_role
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type role.
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:acctyp
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - Total acctyp_role record count is 119
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for acctyp
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for access type objects.
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.CacheLoader] - >>> Started loading cache for my context object.
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: object_customized
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: bui_object_customized
INFO - 2021-07-20 17:14:57 [c.ca.casm.authorization.Authorizer] - Register for changes initialization started for authorization module cache.
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: role
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: func_access_role
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: dcon
INFO - 2021-07-20 17:14:57 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tenant
ERROR - 2021-07-20 17:14:58 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$16(CacheLoader.java:1106)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tenant
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tenant
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:tgm_members
ERROR - 2021-07-20 17:14:58 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$null$18(CacheLoader.java:1249)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
ERROR - 2021-07-20 17:14:58 [c.ca.casm.framework.util.CasmFuture] - Exception occurred, see call stack for more details: userid ()
java.util.concurrent.CompletionException: java.lang.NullPointerException
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331)
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506)
Caused by: java.lang.NullPointerException: null
at com.ca.casm.authorization.CacheLoader.lambda$loadAccTypeCache$20(CacheLoader.java:1387)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:72)
at java.base/java.util.function.BiConsumer.lambda$andThen$0(BiConsumer.java:71)
at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859)
at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837)
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for tgm_members
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: tgm_members
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:cnt
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for cnt
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: cnt
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: acctyp_role
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.cache.ListenerMgr] - Registering for changes for factory: user_context
INFO - 2021-07-20 17:14:58 [c.ca.casm.authorization.Authorizer] - Register for changes finished registration with SDM.
INFO - 2021-07-20 17:14:58 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:master_data_cache
INFO - 2021-07-20 17:14:59 [com.ca.casm.actor.global.ActorUtils] - No cache definition found in SDM, returning default conf for master_data_cache
INFO - 2021-07-20 17:14:59 [c.c.c.a.cache.MasterDataCacheMgr] - Caching for master data factories is disabled.
INFO - 2021-07-20 17:14:59 [com.ca.casm.actor.global.ActorUtils] - Cache configuration NOT defined in xml for the cache name:authentication-cache
ERROR - 2021-07-20 17:14:59 [c.c.c.a.s.AuthenticationServiceSessionCache] - Invalid value specified for SessionTimeout in configuration
----------------------------------------------------------------------------------------------------------------------------------------------------------------
When I execute the batch file to start the incident Microservices from xFlow\APPS\Serivces\incidentmicroservice-17.0.479\bin\incidentmicroservice.bat
Then I get additional echo information in CMD:
Die Datei "C:\Program Files\CA\xFlow\APPS\Services\incidentmicroservice-17.0.479\bin\\\..\INCIDENTMICROSERVICE_config.txt" kann nicht gefunden werden.
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.google.inject.internal.cglib.core.$ReflectUtils$1 (file:/C:/Program%20Files/CA/xFlow/APPS/Services/incidentmicroservice-17.0.479/lib/com.google.inject.guice-4.1.0.jar) to method java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain)
WARNING: Please consider reporting this to the maintainers of com.google.inject.internal.cglib.core.$ReflectUtils$1
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
Oops, cannot start the server.
com.google.inject.CreationException: Unable to create injector, see the following errors:
1) Error injecting constructor, java.lang.ExceptionInInitializerError
at global.GlobalApplicationSettings.<init>(GlobalApplicationSettings.java:97)
at modules.IncidentMSModule.configure(IncidentMSModule.java:42) (via modules: com.google.inject.util.Modules$OverrideModule -> modules.IncidentMSModule)
while locating global.GlobalApplicationSettings
1 error
at com.google.inject.internal.Errors.throwCreationExceptionIfErrorsExist(Errors.java:470)
at com.google.inject.internal.InternalInjectorCreator.injectDynamically(InternalInjectorCreator.java:184)
at com.google.inject.internal.InternalInjectorCreator.build(InternalInjectorCreator.java:110)
at com.google.inject.Guice.createInjector(Guice.java:99)
at com.google.inject.Guice.createInjector(Guice.java:84)
at play.api.inject.guice.GuiceBuilder.injector(GuiceInjectorBuilder.scala:185)
at play.api.inject.guice.GuiceApplicationBuilder.build(GuiceApplicationBuilder.scala:137)
at play.api.inject.guice.GuiceApplicationLoader.load(GuiceApplicationLoader.scala:21)
at play.core.server.ProdServerStart$.start(ProdServerStart.scala:51)
at play.core.server.ProdServerStart$.main(ProdServerStart.scala:25)
at play.core.server.ProdServerStart.main(ProdServerStart.scala)
Caused by: java.lang.ExceptionInInitializerError
at global.GlobalApplicationSettings.<init>(GlobalApplicationSettings.java:193)
at global.GlobalApplicationSettings$$FastClassByGuice$$66c47645.newInstance(<generated>)
at com.google.inject.internal.DefaultConstructionProxyFactory$FastClassProxy.newInstance(DefaultConstructionProxyFactory.java:89)
at com.google.inject.internal.ConstructorInjector.provision(ConstructorInjector.java:111)
at com.google.inject.internal.ConstructorInjector.construct(ConstructorInjector.java:90)
at com.google.inject.internal.ConstructorBindingImpl$Factory.get(ConstructorBindingImpl.java:268)
at com.google.inject.internal.ProviderToInternalFactoryAdapter$1.call(ProviderToInternalFactoryAdapter.java:46)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1092)
at com.google.inject.internal.ProviderToInternalFactoryAdapter.get(ProviderToInternalFactoryAdapter.java:40)
at com.google.inject.internal.SingletonScope$1.get(SingletonScope.java:194)
at com.google.inject.internal.InternalFactoryToProviderAdapter.get(InternalFactoryToProviderAdapter.java:41)
at com.google.inject.internal.InternalInjectorCreator$1.call(InternalInjectorCreator.java:205)
at com.google.inject.internal.InternalInjectorCreator$1.call(InternalInjectorCreator.java:199)
at com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1085)
at com.google.inject.internal.InternalInjectorCreator.loadEagerSingletons(InternalInjectorCreator.java:199)
at com.google.inject.internal.InternalInjectorCreator.injectDynamically(InternalInjectorCreator.java:180)
... 9 more
Caused by: net.sf.ehcache.config.InvalidConfigurationException: There is one error in your configuration:
* Cache 'authentication-cache' error: If your CacheManager has no maxBytesLocalHeap set, you need to either set maxEntriesLocalHeap or maxBytesLocalHeap at the Cache level
at net.sf.ehcache.config.CacheConfiguration.setupFor(CacheConfiguration.java:1704)
at net.sf.ehcache.config.CacheConfiguration.setupFor(CacheConfiguration.java:1688)
at net.sf.ehcache.CacheManager.initializeEhcache(CacheManager.java:1352)
at net.sf.ehcache.CacheManager.addCacheNoCheck(CacheManager.java:1423)
at net.sf.ehcache.CacheManager.addCache(CacheManager.java:1299)
at net.sf.ehcache.CacheManager.addCache(CacheManager.java:1268)
at com.ca.casm.authentication.session.AuthenticationServiceSessionCache.<init>(AuthenticationServiceSessionCache.java:89)
at com.ca.casm.authentication.session.AuthenticationServiceSessionCache.<clinit>(AuthenticationServiceSessionCache.java:61)
... 25 more
----------------------------------------------------------------------------------------------------------------------------------------------------------------
What can cause the xFlow service to be unreachable?
Maybe a wrong environment variable setup for Java?
Currently:
JAVA_HOME: C:\Program Files\CA\SC\JRE\11.0.3
Path: %JAVA_HOME%\bin;....
-->
java -version:
openjdk version "11.0.3" 2019-04-16
OpenJDK Runtime Environment AdoptOpnenJDK (build 11.0.3+7)
OpenJDK 64-Bit Server VM AdoptOpenJDK (build 11.0.3+7, mixed mode)
Any help would be appreciated.
Regards
Christoph
------------------------------
...
------------------------------