[
https://issues.apache.org/jira/browse/LOG4J2-3018?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17286255#comment-17286255
]
Rambabu Bikumandla commented on LOG4J2-3018:
--------------------------------------------
2021-02-17 15:56:40,375 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=Policies,
class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2021-02-17 15:56:40,375 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
createPolicy(=\{TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1,
modulate=true)})
2021-02-17 15:56:40,376 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.RollingFileAppender].
2021-02-17 15:56:40,376 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
RollingFileAppender$Builder(fileName="null",
filePattern="/mastars/log/Remote_%d\{yyyy-MM-dd}.log.gz", append="null",
locking="null",
Policies(CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0,
interval=1, modulate=true)])), Strategy=null, advertise="null",
advertiseUri="null", createOnDemand="null", filePermissions="null",
fileOwner="null", fileGroup="null", bufferedIo="null", bufferSize="null",
immediateFlush="null", ignoreExceptions="null", PatternLayout([%d\{MMM/dd/yy
HH:mm:ss}{GMT+0}] %-5p %c - %m%n), name="Rolling-RemoteDatabaseManager",
Configuration(/apps/opt/weblogic/config/xml/MastarsLogging.xml),
LevelRangeFilter(ERROR), ={})
2021-02-17 15:56:40,377 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Starting RollingFileManager
/mastars/log/Remote_%d\{yyyy-MM-dd}.log.gz
2021-02-17 15:56:40,377 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG PluginManager 'FileConverter'
found 2 plugins
2021-02-17 15:56:40,378 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Setting prev file time to
1969-12-31T19:00:00.000-0500
2021-02-17 15:56:40,378 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Initializing triggering policy
CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0,
interval=1, modulate=true)])
2021-02-17 15:56:40,379 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Initializing triggering policy
TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=true)
2021-02-17 15:56:40,379 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Formatting file name.
useCurrentTime=false. currentFileTime=0, prevFileTime=1613538000000
2021-02-17 15:56:40,380 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Formatting file name.
useCurrentTime=true. currentFileTime=0, prevFileTime=1613538000000
2021-02-17 15:56:40,380 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=filter,
class=org.apache.logging.log4j.core.filter.LevelRangeFilter].
2021-02-17 15:56:40,381 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG createFilter(minLevel="null",
maxLevel="INFO", onMatch="ACCEPT", onMismatch="DENY")
2021-02-17 15:56:40,381 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=layout,
class=org.apache.logging.log4j.core.layout.PatternLayout].
2021-02-17 15:56:40,382 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
PatternLayout$Builder(pattern="[%d\{MMM/dd/yy HH:mm:ss}{GMT+0}] %-5p %c -
%m%n", PatternSelector=null,
Configuration(/apps/opt/weblogic/config/xml/MastarsLogging.xml), Replace=null,
charset="null", alwaysWriteExceptions="null", disableAnsi="null",
noConsoleNoAnsi="null", header="null", footer="null")
2021-02-17 15:56:40,383 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building
Plugin[name=TimeBasedTriggeringPolicy,
class=org.apache.logging.log4j.core.appender.rolling.TimeBasedTriggeringPolicy].
2021-02-17 15:56:40,383 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
TimeBasedTriggeringPolicy$Builder(interval="1", modulate="true",
maxRandomDelay="null")
2021-02-17 15:56:40,383 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=Policies,
class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2021-02-17 15:56:40,384 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
createPolicy(=\{TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1,
modulate=true)})
2021-02-17 15:56:40,384 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.RollingFileAppender].
2021-02-17 15:56:40,385 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
RollingFileAppender$Builder(fileName="null",
filePattern="/mastars/log/Conflict_%d\{yyyy-MM-dd}.log.gz", append="null",
locking="null",
Policies(CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0,
interval=1, modulate=true)])), Strategy=null, advertise="null",
advertiseUri="null", createOnDemand="null", filePermissions="null",
fileOwner="null", fileGroup="null", bufferedIo="null", bufferSize="null",
immediateFlush="null", ignoreExceptions="null", PatternLayout([%d\{MMM/dd/yy
HH:mm:ss}{GMT+0}] %-5p %c - %m%n), name="Rolling-ConflictManager",
Configuration(/apps/opt/weblogic/config/xml/MastarsLogging.xml),
LevelRangeFilter(ERROR), ={})
2021-02-17 15:56:40,386 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Starting RollingFileManager
/mastars/log/Conflict_%d\{yyyy-MM-dd}.log.gz
2021-02-17 15:56:40,386 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG PluginManager 'FileConverter'
found 2 plugins
2021-02-17 15:56:40,387 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Initializing triggering policy
CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0,
interval=1, modulate=true)])
2021-02-17 15:56:40,387 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Initializing triggering policy
TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=true)
2021-02-17 15:56:40,388 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Formatting file name.
useCurrentTime=false. currentFileTime=0, prevFileTime=1613538000000
2021-02-17 15:56:40,388 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Formatting file name.
useCurrentTime=true. currentFileTime=0, prevFileTime=1613538000000
2021-02-17 15:56:40,389 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=filter,
class=org.apache.logging.log4j.core.filter.LevelRangeFilter].
2021-02-17 15:56:40,390 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG createFilter(minLevel="null",
maxLevel="INFO", onMatch="ACCEPT", onMismatch="DENY")
2021-02-17 15:56:40,390 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=layout,
class=org.apache.logging.log4j.core.layout.PatternLayout].
2021-02-17 15:56:40,391 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
PatternLayout$Builder(pattern="[%d\{MMM/dd/yy HH:mm:ss}{GMT+0}] %-5p %c -
%m%n", PatternSelector=null,
Configuration(/apps/opt/weblogic/config/xml/MastarsLogging.xml), Replace=null,
charset="null", alwaysWriteExceptions="null", disableAnsi="null",
noConsoleNoAnsi="null", header="null", footer="null")
2021-02-17 15:56:40,391 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building
Plugin[name=TimeBasedTriggeringPolicy,
class=org.apache.logging.log4j.core.appender.rolling.TimeBasedTriggeringPolicy].
2021-02-17 15:56:40,392 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
TimeBasedTriggeringPolicy$Builder(interval="1", modulate="true",
maxRandomDelay="null")
2021-02-17 15:56:40,392 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=Policies,
class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2021-02-17 15:56:40,393 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
createPolicy(=\{TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1,
modulate=true)})
2021-02-17 15:56:40,576 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
createPolicy(=\{TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1,
modulate=true)})
2021-02-17 15:56:40,576 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.RollingFileAppender].
2021-02-17 15:56:40,577 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG
RollingFileAppender$Builder(fileName="null",
filePattern="/mastars/log/ConflictError_%d\{yyyy-MM-dd}.log.gz", append="null",
locking="null",
Policies(CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0,
interval=1, modulate=true)])), Strategy=null, advertise="null",
advertiseUri="null", createOnDemand="null", filePermissions="null",
fileOwner="null", fileGroup="null", bufferedIo="null", bufferSize="null",
immediateFlush="null", ignoreExceptions="null", PatternLayout([%d\{MMM/dd/yy
HH:mm:ss}{GMT+0}] %-5p %c - %m%n), name="Rolling-ConflictManagerError",
Configuration(/apps/opt/weblogic/config/xml/MastarsLogging.xml),
LevelRangeFilter(FATAL), ={})
2021-02-17 15:56:40,578 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Starting RollingFileManager
/mastars/log/ConflictError_%d\{yyyy-MM-dd}.log.gz
2021-02-17 15:56:40,579 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG PluginManager 'FileConverter'
found 2 plugins
2021-02-17 15:56:40,580 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Setting prev file time to
1969-12-31T19:00:00.000-0500
2021-02-17 15:56:40,580 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Initializing triggering policy
CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0,
interval=1, modulate=true)])
2021-02-17 15:56:40,581 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Initializing triggering policy
TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=true)
2021-02-17 15:56:40,582 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Formatting file name.
useCurrentTime=false. currentFileTime=0, prevFileTime=1613538000000
2021-02-17 15:56:40,582 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Formatting file name.
useCurrentTime=true. currentFileTime=0, prevFileTime=1613538000000
2021-02-17 15:56:40,583 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=filter,
class=org.apache.logging.log4j.core.filter.LevelRangeFilter].
2021-02-17 15:56:40,584 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG createFilter(minLevel="FATAL",
maxLevel="WARN", onMatch="ACCEPT", onMismatch="DENY")
2021-02-17 15:56:40,585 [STANDBY] ExecuteThread: '2' for queue:
'weblogic.kernel.Default (self-tuning)' DEBUG Building Plugin[name=layout,
class=org.apache.logging.log4j.core.layout.PatternLayout].
> Rollover log file at midnight not working
> -----------------------------------------
>
> Key: LOG4J2-3018
> URL: https://issues.apache.org/jira/browse/LOG4J2-3018
> Project: Log4j 2
> Issue Type: Bug
> Reporter: Rambabu Bikumandla
> Priority: Blocker
> Labels: log4j2.xml, routing
> Attachments: gz_onBothOldNewFile.log, log4j2 (1).xml, log4j2.xml
>
>
> Hi Team,
> I am migrating my project from log4j1.x to log4j2.11.2 version, while
> migration am seeing below issue could you please provide solution on it.
> *Log4j2 version :*
> *log4j-slf4j-impl-2.11.2*
> *log4j-1.2-api-2.11.2*
> *log4j-1.2-core-2.11.2*
> Issue: log files are not roll out every day midnight with new file name, it
> still writing it to old logs for some hours then switching to new logs.
> Below my log4j2.xml config.
> <Routing name="DEBUG">
> <Routes pattern="$${ctx:ServiceName}">
> <Route key="ConsuerManager">
> <RollingFile name="Rolling-${ctx:ServiceName}"
> {color:#ff0000}*fileName="/app/log/Consumer_${date:yyyy-MM-dd}.log*{color}"
> *{color:#ff0000}filePattern="/app/log/Consumer_%d\{yyyy-MM-dd}.log{color}*"
> append="true">
> <LevelRangeFilter minLevel="INFO" maxLevel="DEBUG" onMatch="ACCEPT"
> onMismatch="DENY" />
> <PatternLayout pattern="${LOG_PATTERN}" />
> <Policies>
> <TimeBasedTriggeringPo licy interval="1" modulate="true" />
> </Policies>
> </RollingFile>
> </Route>
> <Route key="AppProvider">
> <RollingFile name="Rolling-${ctx:ServiceName}"
> fileName="/app/log/Provider_${date:yyyy-MM-dd}.log"
> filePattern="/app/log/Provider_%d\{yyyy-MM-dd}.log" append="true">
> <LevelRangeFilter minLevel="INFO" maxLevel="DEBUG" onMatch="ACCEPT"
> onMismatch="DENY" />
> <PatternLayout pattern="${LOG_PATTERN}" />
> <Policies>
> <TimeBasedTriggeringPolicy interval="1" modulate="true" />
> </Policies></RollingFile></Route>
> </Routes>
> </Routing>
> *Sample Logs: consumer_2021-02-08.log - It should contain only Feb-08 but we
> are seeing Feb-09 also*
> [Feb/08/21 18:18:37] INFO com.app.apptest.Database.DbRequest - approval
> status of the myno: 125478412 Is ::W
> [Feb/08/21 18:18:37] INFO
> com.myapp.servicecore.ejb.monitor.AppTestsEJBMonitor
> [Feb/08/21 18:18:37] INFO
> com.myapp.servicecore.ejb.monitor.AppTestsEJBMonitor
> [Feb/08/21 18:18:37] INFO com.bi.myapp.consumer.utils.ConsumerHelper -
> conditional status for the myno ::4857154 is :: false
> [Feb/08/21 18:18:37] INFO com.app.apptest.Database.Database - Database
> initialized
> [Feb/08/21 18:18:37] INFO com.app.apptest.MyManagement.Database - Database
> initialized
> [Feb/08/21 18:18:38] INFO com.app.apptest.Database.DbTtion - mynoId :123456
> mynoID :45678
> [Feb/08/21 18:18:38] INFO com.bi.persistent.process.DBProcess - db is
> ::org.apache.db.oracle.DBCommandOracle@516c36a0
> [Feb/08/21 18:18:38] INFO com.bi.persistent.process.DBProcess - db is
> ::org.apache.db.oracle.DBCommandOracle@4ee951e2
> [{color:#FF0000}Feb/08/21 18:18:38{color}] INFO
> com.bi.myapp.restclients.CircuitImpactClient - constructor: Using environment
> Test to get URL info
> ..
> ..
> {color:#FF0000}[Feb/09/21 12:37:43{color}] INFO com.bi.myapp.util.Client -
> connectToId: Trying to connect to mail server;
> [Feb/09/21 12:37:44] INFO com.bi.myapp.util.Client - connectToId:
> Successfully connected to .
> [Feb/09/21 12:37:45] INFO com.bi.myapp.util.Client - readMails: There are 0
> messages to be processed
> [Feb/09/21 12:37:45] INFO com.bi.myapp.util.Client - readMails: Number of
> unread messages still in the
> [Feb/09/21 12:37:45] INFO
> com.myapp.servicecore.ejb.monitor.AppTestsEJBMonitor - updateLock invoked at
> 12:37:45 PM GMT took 3 milliseconds to execute.
> [Feb/09/21 12:37:45] INFO com.bi.myapp.scheduler.EmailReaderScheduler -
> ~
> "consumer_2021-02-08.log" [readonly] 6327556L, 273633483C
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
