回复：StatusLogger output help
I just want to know when StatusLogger will happen, find this question: http://qnalist.com/questions/4783598/help-on-statuslogger-output but no one reply it. seems this question ask Mar 2014. no one notice then. I just pull it out, hope someone answer this. TKS. qihuang.zheng 原始邮件 发件人:qihuang.zhengqihuang.zh...@fraudmetrix.cn 收件人:useru...@cassandra.apache.org 发送时间:2015年10月25日(周日) 15:19 主题:StatusLogger output help After dig system.log. I notice Every GC for ParNew or GC for CMS will produce StatusLogger. But I’m not really sure is GC cause it. Is anyone summary this appearance? INFO [ScheduledTasks:1] 2015-10-21 20:57:54,568 GCInspector.java (line 116) GC for ParNew: 1165 ms for 1 collections, 8289178440 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 20:57:54,621 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 20:57:54,622 StatusLogger.java (line 70) ReadStage 521 613058 0 0 …. INFO [ScheduledTasks:1] 2015-10-21 21:03:19,142 GCInspector.java (line 116) GC for ParNew: 267 ms for 1 collections, 9969463696 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:03:56,802 GCInspector.java (line 116) GC for ParNew: 1549 ms for 1 collections, 10679738240 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:03:56,803 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 21:03:56,804 StatusLogger.java (line 70) ReadStage 3 9 628954 0 0 …. INFO [ScheduledTasks:1] 2015-10-21 21:09:42,724 GCInspector.java (line 116) GC for ParNew: 1420 ms for 1 collections, 5274947408 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:09:42,725 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 21:09:42,725 StatusLogger.java (line 70) ReadStage 7 7 644911 0 0 qihuang.zheng
回复：StatusLogger output help
for ensure after GC , there will print StatusLogger, I found this code in GCInspector.java: if (durationPerCollection MIN_DURATION_TPSTATS) StatusLogger.log(); the defaultMIN_DURATION_TPSTATS=1second. so if GC duration more than 1s, there will be StatusLogger! But I check StatusLogger, I could’t figure out what happen inside in C at that moment. which status msg should I care on StatusLogger’s print msg?? Does Pending count important? or should I care aboutMemtable ops,data qihuang.zheng 原始邮件 发件人:qihuang.zhengqihuang.zh...@fraudmetrix.cn 收件人:useru...@cassandra.apache.org 发送时间:2015年10月25日(周日) 15:27 主题:回复：StatusLogger output help I just want to know when StatusLogger will happen, find this question: http://qnalist.com/questions/4783598/help-on-statuslogger-output but no one reply it. seems this question ask Mar 2014. no one notice then. I just pull it out, hope someone answer this. TKS. qihuang.zheng 原始邮件 发件人:qihuang.zhengqihuang.zh...@fraudmetrix.cn 收件人:useru...@cassandra.apache.org 发送时间:2015年10月25日(周日) 15:19 主题:StatusLogger output help After dig system.log. I notice Every GC for ParNew or GC for CMS will produce StatusLogger. But I’m not really sure is GC cause it. Is anyone summary this appearance? INFO [ScheduledTasks:1] 2015-10-21 20:57:54,568 GCInspector.java (line 116) GC for ParNew: 1165 ms for 1 collections, 8289178440 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 20:57:54,621 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 20:57:54,622 StatusLogger.java (line 70) ReadStage 521 613058 0 0 …. INFO [ScheduledTasks:1] 2015-10-21 21:03:19,142 GCInspector.java (line 116) GC for ParNew: 267 ms for 1 collections, 9969463696 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:03:56,802 GCInspector.java (line 116) GC for ParNew: 1549 ms for 1 collections, 10679738240 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:03:56,803 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 21:03:56,804 StatusLogger.java (line 70) ReadStage 3 9 628954 0 0 …. INFO [ScheduledTasks:1] 2015-10-21 21:09:42,724 GCInspector.java (line 116) GC for ParNew: 1420 ms for 1 collections, 5274947408 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:09:42,725 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 21:09:42,725 StatusLogger.java (line 70) ReadStage 7 7 644911 0 0 qihuang.zheng
StatusLogger output help
After dig system.log. I notice Every GC for ParNew or GC for CMS will produce StatusLogger. But I’m not really sure is GC cause it. Is anyone summary this appearance? INFO [ScheduledTasks:1] 2015-10-21 20:57:54,568 GCInspector.java (line 116) GC for ParNew: 1165 ms for 1 collections, 8289178440 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 20:57:54,621 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 20:57:54,622 StatusLogger.java (line 70) ReadStage 521 613058 0 0 …. INFO [ScheduledTasks:1] 2015-10-21 21:03:19,142 GCInspector.java (line 116) GC for ParNew: 267 ms for 1 collections, 9969463696 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:03:56,802 GCInspector.java (line 116) GC for ParNew: 1549 ms for 1 collections, 10679738240 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:03:56,803 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 21:03:56,804 StatusLogger.java (line 70) ReadStage 3 9 628954 0 0 …. INFO [ScheduledTasks:1] 2015-10-21 21:09:42,724 GCInspector.java (line 116) GC for ParNew: 1420 ms for 1 collections, 5274947408 used; max is 16750411776 INFO [ScheduledTasks:1] 2015-10-21 21:09:42,725 StatusLogger.java (line 55) Pool Name Active Pending Completed Blocked All Time Blocked INFO [ScheduledTasks:1] 2015-10-21 21:09:42,725 StatusLogger.java (line 70) ReadStage 7 7 644911 0 0 qihuang.zheng
StatusLogger output help
Hi, In my cassandra logs, I see a lot of StatusLogger output lines. I'm trying to understand why this is logged, and how to interpret the output. Maybe someone can point me to some documentation on this particular logging aspect? I would like to know what is triggering the StatusLogger.java to start logging? Sometimes it logs every few seconds, and sometimes it won't log for hours. Also, about the lines that log Memtable ops, data per ColumnFamily, what do these figures mean? Is it number of operations and data size (bytes, MB, ...)? Are the ops counters reset every time they are logged, or e.g. every x minutes? Any help is greatly appreciated! Thanks, Tom
