Daily Usenet report

Jul 23 03:00:04 -- Jul 24 03:00:05

Unknown entries from news log file:

First 50 / 83 lines (60.2%)

Jul 23 03:01:26 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 23 06:21:44 twilightnode nnrpd[487250]: Reading access from /usr/local/news/etc/readers.conf
Jul 23 06:21:44 twilightnode nnrpd[487250]: Auth strategy 'localhost' does not match client. Removing.
Jul 23 06:21:44 twilightnode nnrpd[487250]: 69.5.169.158 res <anonymous>
Jul 23 06:21:44 twilightnode nnrpd[487250]: 69.5.169.158 no_match_user <anonymous> <localhost>
Jul 23 06:21:44 twilightnode nnrpd[487250]: 69.5.169.158 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 23 06:21:44 twilightnode nnrpd[487250]: 69.5.169.158 match_user <anonymous> <anonymous>
Jul 23 06:21:44 twilightnode nnrpd[487250]: 69.5.169.158 no_post anonymous-read
Jul 23 06:21:52 twilightnode nnrpd[487251]: Reading access from /usr/local/news/etc/readers.conf
Jul 23 06:21:52 twilightnode nnrpd[487251]: Auth strategy 'localhost' does not match client. Removing.
Jul 23 06:21:52 twilightnode nnrpd[487251]: 194.50.235.152 res <anonymous>
Jul 23 06:21:52 twilightnode nnrpd[487251]: 194.50.235.152 no_match_user <anonymous> <localhost>
Jul 23 06:21:52 twilightnode nnrpd[487251]: 194.50.235.152 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 23 06:21:52 twilightnode nnrpd[487251]: 194.50.235.152 match_user <anonymous> <anonymous>
Jul 23 06:21:52 twilightnode nnrpd[487251]: 194.50.235.152 no_post anonymous-read
Jul 23 07:56:50 twilightnode nnrpd[492680]: Reading access from /usr/local/news/etc/readers.conf
Jul 23 07:56:50 twilightnode nnrpd[492680]: Auth strategy 'localhost' does not match client. Removing.
Jul 23 07:56:50 twilightnode nnrpd[492680]: azpdcs8z4423.stretchoid.com res <anonymous>
Jul 23 07:56:50 twilightnode nnrpd[492680]: azpdcs8z4423.stretchoid.com no_match_user <anonymous> <localhost>
Jul 23 07:56:50 twilightnode nnrpd[492680]: azpdcs8z4423.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 23 07:56:50 twilightnode nnrpd[492680]: azpdcs8z4423.stretchoid.com match_user <anonymous> <anonymous>
Jul 23 07:56:50 twilightnode nnrpd[492680]: azpdcs8z4423.stretchoid.com no_post anonymous-read
Jul 23 07:57:00 twilightnode nnrpd[492681]: Reading access from /usr/local/news/etc/readers.conf
Jul 23 07:57:00 twilightnode nnrpd[492681]: Auth strategy 'localhost' does not match client. Removing.
Jul 23 07:57:00 twilightnode nnrpd[492681]: azpdcs8z4423.stretchoid.com res <anonymous>
Jul 23 07:57:00 twilightnode nnrpd[492681]: azpdcs8z4423.stretchoid.com no_match_user <anonymous> <localhost>
Jul 23 07:57:00 twilightnode nnrpd[492681]: azpdcs8z4423.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 23 07:57:00 twilightnode nnrpd[492681]: azpdcs8z4423.stretchoid.com match_user <anonymous> <anonymous>
Jul 23 07:57:00 twilightnode nnrpd[492681]: azpdcs8z4423.stretchoid.com no_post anonymous-read
Jul 23 11:10:42 twilightnode nnrpd[500769]: Reading access from /usr/local/news/etc/readers.conf
Jul 23 11:10:42 twilightnode nnrpd[500769]: Auth strategy 'localhost' does not match client. Removing.
Jul 23 11:10:42 twilightnode nnrpd[500769]: o016.scanner.modat.io res <anonymous>
Jul 23 11:10:42 twilightnode nnrpd[500769]: o016.scanner.modat.io no_match_user <anonymous> <localhost>
Jul 23 11:10:42 twilightnode nnrpd[500769]: o016.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 23 11:10:42 twilightnode nnrpd[500769]: o016.scanner.modat.io match_user <anonymous> <anonymous>
Jul 23 11:10:42 twilightnode nnrpd[500769]: o016.scanner.modat.io no_post anonymous-read
Jul 23 11:26:08 twilightnode nnrpd[501460]: Reading access from /usr/local/news/etc/readers.conf
Jul 23 11:26:08 twilightnode nnrpd[501460]: Auth strategy 'localhost' does not match client. Removing.
Jul 23 11:26:08 twilightnode nnrpd[501460]: o012.scanner.modat.io res <anonymous>
Jul 23 11:26:08 twilightnode nnrpd[501460]: o012.scanner.modat.io no_match_user <anonymous> <localhost>
Jul 23 11:26:08 twilightnode nnrpd[501460]: o012.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 23 11:26:08 twilightnode nnrpd[501460]: o012.scanner.modat.io match_user <anonymous> <anonymous>
Jul 23 11:26:08 twilightnode nnrpd[501460]: o012.scanner.modat.io no_post anonymous-read
Jul 23 18:55:35 twilightnode nnrpd[521126]: Reading access from /usr/local/news/etc/readers.conf
Jul 23 18:55:35 twilightnode nnrpd[521126]: Auth strategy 'localhost' does not match client. Removing.
Jul 23 18:55:35 twilightnode nnrpd[521126]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net res <anonymous>
Jul 23 18:55:35 twilightnode nnrpd[521126]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net no_match_user <anonymous> <localhost>
Jul 23 18:55:35 twilightnode nnrpd[521126]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 23 18:55:35 twilightnode nnrpd[521126]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net match_user <anonymous> <anonymous>
Jul 23 18:55:35 twilightnode nnrpd[521126]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6665 71.8%1003.0 KB 72.0%
innd 1268 13.7%201.2 KB 14.4%
innfeed 1226 13.2%174.8 KB 12.5%
nnrpd 127 1.4%13.6 KB 1.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 9287 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14087 46.7%
Negative hits 9459 31.3%
Do not exist 6600 21.9%
Cache misses 45 0.1%
TOTAL: 4 30191 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.016 0.0%6 0.000 2.667 8.000
article cleanup00:00:00.078 0.0%6617 0.000 0.012 0.062
article logging00:00:00.275 0.0%6655 0.000 0.041 0.154
article parse00:00:00.109 0.0%9278 0.000 0.012 0.072
article write00:00:05.394 0.0%4616 0.458 1.169 3.733
data move00:00:00.100 0.0%33546 0.000 0.003 0.027
hisgrep/artcncl00:00:00.003 0.0%6 0.000 0.500 1.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.809 0.0%30263 0.068 0.192 0.582
history sync00:00:00.068 0.0%412 0.000 0.165 1.000
history write00:00:04.209 0.0%6617 0.179 0.636 1.286
idle24:00:29.206100.0%32658 1159.132 2646.494 17646.735
nntp read00:00:00.770 0.0%32317 0.000 0.024 0.100
overview write00:00:03.108 0.0%4616 0.264 0.673 4.154
perl filter00:00:11.502 0.0%6609 0.957 1.740 16.222
python filter00:00:00.641 0.0%6609 0.000 0.097 1.111
site send00:00:00.154 0.0%4614 0.000 0.033 0.143
TOTAL: 1724:01:01.442100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%4689 0.000 0.004 0.143
article prepare00:00:00.002 0.0%16676 0.000 0.000 0.007
article read00:00:00.854 0.0%3150 0.000 0.271 2.000
backlog stats00:00:00.044 0.0%49990 0.000 0.001 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.453 0.0%27261 0.000 0.017 0.062
data write00:00:00.829 0.0%22531 0.000 0.037 0.149
idle23:58:50.381100.0%49990 447.540 1726.953 5786.856
status file00:00:00.202 0.0%227 0.000 0.890 5.000
TOTAL: 923:58:52.783100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:13.157100.0%17 76.000 773.941 10599.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%43 0.000 0.047 0.500
TOTAL: 300:00:13.159100.0%----

Control commands to innd:

CommandNumber
flush 2
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 166

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 2 11272 4038 5892 1342 35%50:16:18
2newsfeed.endofthelinebbs.com 2 4919 546 3877 496 11%23:40:35
3news.tebibyte.org 1 7346 77 7050 219 1%35:09:24
4news.sklaffkom.se 45 71 34 18 19 47%09:30:24
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 75 23608 4695 16837 2076 19%118:36:41
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.7 MB0.0 KB4.4 MB16.0 MB 72%3.1 KB
2newsfeed.endofthelinebbs.com5.8 MB0.0 KB1.5 MB7.3 MB 79%7.2 KB
3news.tebibyte.org288.4 KB0.0 KB435.4 KB723.7 KB 39%2.4 KB
4news.sklaffkom.se131.8 KB0.0 KB40.2 KB172.0 KB 76%3.2 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 517.9 MB0.0 KB6.3 MB24.2 MB 73%3.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 23 03:00:04 - 03:59:59 88 1.9% 0.02412.8 KB 2.3% 0.11
Jul 23 04:00:00 - 04:59:59 183 4.0% 0.05877.9 KB 4.8% 0.24
Jul 23 05:00:00 - 05:59:59 73 1.6% 0.02330.8 KB 1.8% 0.09
Jul 23 06:00:00 - 06:59:59 121 2.6% 0.03682.3 KB 3.7% 0.19
Jul 23 07:00:00 - 07:59:59 149 3.2% 0.04541.4 KB 3.0% 0.15
Jul 23 08:00:00 - 08:59:59 168 3.6% 0.05522.9 KB 2.9% 0.15
Jul 23 09:00:00 - 09:59:59 216 4.7% 0.06656.5 KB 3.6% 0.18
Jul 23 10:00:00 - 10:59:59 201 4.3% 0.06853.3 KB 4.7% 0.24
Jul 23 11:00:00 - 11:59:59 237 5.1% 0.07805.5 KB 4.4% 0.22
Jul 23 12:00:00 - 12:59:59 202 4.4% 0.06847.6 KB 4.6% 0.24
Jul 23 13:00:00 - 13:59:59 206 4.5% 0.06641.0 KB 3.5% 0.18
Jul 23 14:00:00 - 14:59:59 194 4.2% 0.05789.0 KB 4.3% 0.22
Jul 23 15:00:00 - 15:59:59 258 5.6% 0.07976.2 KB 5.4% 0.27
Jul 23 16:00:00 - 16:59:59 274 5.9% 0.081.1 MB 6.4% 0.32
Jul 23 17:00:00 - 17:59:59 231 5.0% 0.06736.6 KB 4.0% 0.20
Jul 23 18:00:00 - 18:59:59 257 5.6% 0.071009.4 KB 5.5% 0.28
Jul 23 19:00:00 - 19:59:59 289 6.3% 0.081.1 MB 6.3% 0.32
Jul 23 20:00:00 - 20:59:59 240 5.2% 0.07839.5 KB 4.6% 0.23
Jul 23 21:00:00 - 21:59:59 228 4.9% 0.06979.2 KB 5.4% 0.27
Jul 23 22:00:00 - 22:59:59 211 4.6% 0.06736.2 KB 4.0% 0.20
Jul 23 23:00:00 - 23:59:59 185 4.0% 0.05844.5 KB 4.6% 0.23
Jul 24 00:00:00 - 00:59:59 174 3.8% 0.05698.9 KB 3.8% 0.19
Jul 24 01:00:00 - 01:59:59 124 2.7% 0.03879.7 KB 4.8% 0.24
Jul 24 02:00:00 - 02:59:59 103 2.2% 0.03261.7 KB 1.4% 0.07
Jul 24 03:00:00 - 03:00:05 9 0.2% 1.808.6 KB 0.0% 1.72
TOTAL: 24:00:01 4621 100.0% 0.0517.8 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1334 1236 0 0 0 0 0 0 98
2newsfeed.endofthelinebbs.com 499 87 0 0 0 8 0 0 404
3news.tebibyte.org 194 171 0 0 0 0 0 0 23
4news.sklaffkom.se 17 17 0 0 0 0 0 0 0
TOTAL: 4 2044 1511 0 0 0 8 0 0 525

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.sex.voyerism 106
alt.slack 76
alt.usage.english 67
linux.debian.vote 64
pl.soc.prawo 55
alt.checkmate 54
pl.misc.elektronika 41
hfx.general 33
pl.soc.polityka 33
uk.legal.moderated 31
pl.misc.samochody 27
uk.tech.broadcast 25
alt.home.repair 23
de.alt.fan.pluesch 23
lada.talk 23
cn.comp 20
alt.fan.rush-limbaurgh 17
alt.food.fast-food 17
alt.sex.voyeurism 17
de.etc.bahn.bahnpolitik 17
TOTAL: 214 1511

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 464
EMP (phn path) 13
User-issued cancel 6
HTML Multipart 2
Too many newsgroups (meow) 2
TOTAL: 5 487

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 38
TOTAL: 1 38
TOTAL: 1 38

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6160 1039 3579 3 0 7 16%23:59:57
2sklaffkom.se 4849 821 1512 2290 0 15 16%23:59:57
3newsfeed.endofthelinebbs.com 7187 16 4045 9 0 51 0%23:59:53
4weretis.net 674 0 670 0 0 0 0%22:25:27
TOTAL: 4 18870 1876 9806 2302 0 73 9%94:25:14
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.1 MB15.5 KB3.1 MB0.0 KB/s3.0 KB23:59:57
2sklaffkom.se1.3 MB11.8 MB13.1 MB0.2 KB/s4.3 KB23:59:57
3newsfeed.endofthelinebbs.com71.5 KB36.8 KB108.3 KB0.0 KB/s4.3 KB23:59:53
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:25:27
TOTAL: 44.4 MB11.9 MB16.3 MB0.0 KB/s4.0 KB94:25:14
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
164.89.162.15 2 00.0 KB 0 0 000:00:00
2azpdcs8z4423.stretchoid.com 2 00.0 KB 0 0 000:00:10
3102.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
5194.164.107.5 1 00.0 KB 0 0 000:00:00
6194.50.235.152 1 00.0 KB 0 0 000:00:00
769.5.169.158 1 00.0 KB 0 0 000:00:00
8o012.scanner.modat.io 1 00.0 KB 0 0 000:00:00
9o016.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 9 11 00.0 KB 0 0 000:00:13

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 00.0 KB 0 0 000:00:01
2*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
5*.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
TOTAL: 5 11 00.0 KB 0 0 000:00:13

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcs8z4423.stretchoid.com 0.010 0.017 0.00000:00:10
102.195.132.66.censys-scanner.com 0.037 0.015 0.00000:00:00
194.50.235.152 0.003 0.008 0.00000:00:00
64.89.162.15 0.007 0.016 0.00000:00:00
194.164.107.5 0.001 0.011 0.00000:00:00
o012.scanner.modat.io 0.005 0.007 0.00000:00:00
69.5.169.158 0.006 0.016 0.00000:00:00
o016.scanner.modat.io 0.002 0.013 0.00000:00:00
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 0.003 0.010 0.00000:00:00
TOTAL: 9 0.074 0.113 0.00000:00:13

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
102.195.132.66.censys-scanner.com 1
azpdcs8z4423.stretchoid.com 1
TOTAL: 2 2

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
194.50.235.152 1 1
TOTAL: 1 1 2
innreport from INN 2.7.3 based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then