Hi,
I am analysing the log files of our Openfire and I am seeing very many and strange output in the warn.log. I mean VERY many lines like the following, printed very often even within one second.
The whole warn.log file is 1 GB within half an hour only with these lines (making it unusable):
Anybody knows, what this is and means? Is it harmful? Can (or should) it be suppressed?
We are running Openfire 3.10.3 with 4 Cluster nodes, Hazelcast plugin 2.1.2.
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@3f564a75
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@17b37019
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@433d64e2
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@73aa53ea
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@3a3267cf
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@336b62f9
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@f6e11ff
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@4f2ee3ce
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@35f8443
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@73e44c6f
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@115646da
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@37459b1a
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@1cac1cb0
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@678f7584
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@7b6c6239
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@8a4cb7d
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@7b2b7ff9
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@64def39b
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@694b78d9
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] Asking if operation execution has been started: com.hazelcast.spi.impl.operationservice.impl.IsStillRunningService$InvokeIsStillRunningOperationRunnable@3ac9e44f
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2092876, invocationTime=1453384658961, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2114582, invocationTime=1453384750198, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2226885, invocationTime=1453385089566, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2216631, invocationTime=1453385064437, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2156922, invocationTime=1453384898901, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2308671, invocationTime=1453385270252, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2112354, invocationTime=1453384741910, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2218260, invocationTime=1453385069301, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2187099, invocationTime=1453384985337, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2083272, invocationTime=1453384610297, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2125175, invocationTime=1453384791701, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2073215, invocationTime=1453384554298, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2221479, invocationTime=1453385076511, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2156917, invocationTime=1453384898889, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2251292, invocationTime=1453385147664, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2074800, invocationTime=1453384562855, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2217817, invocationTime=1453385067458, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2117887, invocationTime=1453384763411, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}
2016.01.21 15:09:18 com.hazelcast.spi.impl.operationservice.impl.Invocation - [172.25.3.62]:5701 [openfire] [3.5.1] 'is-executing': true -> Invocation{ serviceName='hz:impl:executorService', op=com.hazelcast.executor.impl.operations.MemberCallableTaskOperation{serviceName='null', partitionId=-1, callId=2140539, invocationTime=1453384846032, waitTimeout=-1, callTimeout=30000}, partitionId=-1, replicaIndex=0, tryCount=250, tryPauseMillis=500, invokeCount=1, callTimeout=30000, target=Address[172.25.3.63]:5701, backupsExpected=0, backupsCompleted=0}