Daily Usenet report

Sep 8 03:00:04 -- Sep 9 03:00:04

Unknown entries from news log file:

First 50 / 97 lines (51.5%)

Sep 8 03:01:03 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 8 03:25:11 twilightnode nnrpd[3018197]: Reading access from /usr/local/news/etc/readers.conf
Sep 8 03:25:11 twilightnode nnrpd[3018197]: Auth strategy 'localhost' does not match client. Removing.
Sep 8 03:25:11 twilightnode nnrpd[3018197]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 8 03:25:11 twilightnode nnrpd[3018197]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 8 03:25:11 twilightnode nnrpd[3018197]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 8 03:25:11 twilightnode nnrpd[3018197]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 8 03:25:11 twilightnode nnrpd[3018197]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 8 03:25:22 twilightnode nnrpd[3018200]: Reading access from /usr/local/news/etc/readers.conf
Sep 8 03:25:22 twilightnode nnrpd[3018200]: Auth strategy 'localhost' does not match client. Removing.
Sep 8 03:25:22 twilightnode nnrpd[3018200]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 8 03:25:22 twilightnode nnrpd[3018200]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 8 03:25:22 twilightnode nnrpd[3018200]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 8 03:25:22 twilightnode nnrpd[3018200]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 8 03:25:22 twilightnode nnrpd[3018200]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 8 03:25:23 twilightnode nnrpd[3018201]: Reading access from /usr/local/news/etc/readers.conf
Sep 8 03:25:23 twilightnode nnrpd[3018201]: Auth strategy 'localhost' does not match client. Removing.
Sep 8 03:25:23 twilightnode nnrpd[3018201]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 8 03:25:23 twilightnode nnrpd[3018201]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 8 03:25:23 twilightnode nnrpd[3018201]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 8 03:25:23 twilightnode nnrpd[3018201]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 8 03:25:23 twilightnode nnrpd[3018201]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 8 03:25:24 twilightnode nnrpd[3018202]: Reading access from /usr/local/news/etc/readers.conf
Sep 8 03:25:24 twilightnode nnrpd[3018202]: Auth strategy 'localhost' does not match client. Removing.
Sep 8 03:25:24 twilightnode nnrpd[3018202]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 8 03:25:24 twilightnode nnrpd[3018202]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 8 03:25:24 twilightnode nnrpd[3018202]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 8 03:25:24 twilightnode nnrpd[3018202]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 8 03:25:24 twilightnode nnrpd[3018202]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 8 03:25:33 twilightnode nnrpd[3018203]: Reading access from /usr/local/news/etc/readers.conf
Sep 8 03:25:33 twilightnode nnrpd[3018203]: Auth strategy 'localhost' does not match client. Removing.
Sep 8 03:25:33 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 8 03:25:33 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 8 03:25:33 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 8 03:25:33 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 8 03:25:33 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 8 03:25:38 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 8 03:25:38 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 8 03:25:38 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 8 03:25:38 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 8 03:25:38 twilightnode nnrpd[3018203]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 8 04:32:40 twilightnode nnrpd[3021167]: Reading access from /usr/local/news/etc/readers.conf
Sep 8 04:32:40 twilightnode nnrpd[3021167]: Auth strategy 'localhost' does not match client. Removing.
Sep 8 04:32:40 twilightnode nnrpd[3021167]: o033.scanner.modat.io res <anonymous>
Sep 8 04:32:40 twilightnode nnrpd[3021167]: o033.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 8 04:32:40 twilightnode nnrpd[3021167]: o033.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 8 04:32:40 twilightnode nnrpd[3021167]: o033.scanner.modat.io match_user <anonymous> <anonymous>
Sep 8 04:32:40 twilightnode nnrpd[3021167]: o033.scanner.modat.io no_post anonymous-read
Sep 8 05:01:16 twilightnode nnrpd[3022513]: Reading access from /usr/local/news/etc/readers.conf
Sep 8 05:01:16 twilightnode nnrpd[3022513]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7643 55.1%1.3 MB 60.7%
innd 4835 34.8%655.7 KB 29.4%
innfeed 1248 9.0%205.4 KB 9.2%
nnrpd 155 1.1%16.4 KB 0.7%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 13882 100.0%2.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20037 51.3%
Negative hits 11426 29.2%
Do not exist 7591 19.4%
Cache misses 28 0.1%
TOTAL: 4 39082 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.087 0.0%32 0.000 2.719 18.000
article cleanup00:00:00.062 0.0%7605 0.000 0.008 0.200
article logging00:00:00.249 0.0%7644 0.000 0.033 0.246
article parse00:00:00.116 0.0%10255 0.000 0.011 0.200
article write00:00:06.613 0.0%6182 0.581 1.070 3.053
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.198 0.0%41277 0.000 0.005 0.030
hisgrep/artcncl00:00:00.007 0.0%32 0.000 0.219 0.667
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.983 0.0%39165 0.053 0.102 0.211
history sync00:00:00.042 0.0%312 0.000 0.135 3.500
history write00:00:03.128 0.0%7605 0.120 0.411 0.944
idle23:59:51.024 99.9%42331 795.093 2040.845 10006.833
nntp read00:00:00.801 0.0%41366 0.000 0.019 0.057
overview write00:00:03.884 0.0%6182 0.286 0.628 1.840
perl filter00:00:11.705 0.0%7605 0.860 1.539 9.111
python filter00:00:00.908 0.0%7605 0.000 0.119 0.481
site send00:00:00.303 0.0%12340 0.000 0.025 0.167
TOTAL: 1824:00:23.110100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.036 0.0%6460 0.000 0.006 0.051
article prepare00:00:00.002 0.0%26660 0.000 0.000 0.004
article read00:00:00.931 0.0%4299 0.000 0.217 1.062
backlog stats00:00:00.069 0.0%82783 0.000 0.001 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.610 0.0%46415 0.000 0.013 0.036
data write00:00:00.957 0.0%40249 0.000 0.024 0.065
idle23:50:23.346100.0%82783 350.214 1036.727 3778.340
status file00:00:00.103 0.0%159 0.000 0.648 16.000
TOTAL: 923:50:26.054100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:24.280100.0%19 3.500 1277.895 4208.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%99 0.000 0.020 0.500
TOTAL: 300:00:24.282100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 11594 4420 6979 195 38%47:36:00
2usenet.blueworldhosting.com 718 7542 1152 5282 1108 15%23:45:49
3newsfeed.endofthelinebbs.com 2 4578 244 4210 124 5%22:40:29
4news.tebibyte.org 1 818 111 707 0 13%08:18:00
5news.sklaffkom.se 40 115 77 38 0 66%11:32:05
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 789 24647 6004 17216 1427 24%113:52:23
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.1 MB0.0 KB631.1 KB13.8 MB 95%3.1 KB
2usenet.blueworldhosting.com6.0 MB0.0 KB1.7 MB7.7 MB 77%3.5 KB
3newsfeed.endofthelinebbs.com2.7 MB0.0 KB197.1 KB2.9 MB 93%8.1 KB
4news.tebibyte.org322.1 KB0.0 KB0.0 KB322.1 KB100%2.9 KB
5news.sklaffkom.se245.9 KB0.0 KB0.0 KB245.9 KB100%3.2 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 622.4 MB0.0 KB2.5 MB24.9 MB 89%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 8 03:00:04 - 03:59:59 99 1.6% 0.03482.2 KB 2.0% 0.13
Sep 8 04:00:00 - 04:59:59 127 2.1% 0.04518.8 KB 2.2% 0.14
Sep 8 05:00:00 - 05:59:59 109 1.8% 0.03481.1 KB 2.0% 0.13
Sep 8 06:00:00 - 06:59:59 134 2.2% 0.04505.9 KB 2.1% 0.14
Sep 8 07:00:00 - 07:59:59 150 2.4% 0.04577.0 KB 2.4% 0.16
Sep 8 08:00:00 - 08:59:59 213 3.4% 0.06902.6 KB 3.7% 0.25
Sep 8 09:00:00 - 09:59:59 316 5.1% 0.09991.3 KB 4.1% 0.28
Sep 8 10:00:00 - 10:59:59 311 5.0% 0.091.2 MB 5.2% 0.35
Sep 8 11:00:00 - 11:59:59 365 5.9% 0.101.1 MB 4.6% 0.31
Sep 8 12:00:00 - 12:59:59 299 4.8% 0.081.1 MB 4.7% 0.31
Sep 8 13:00:00 - 13:59:59 386 6.2% 0.111.1 MB 4.8% 0.32
Sep 8 14:00:00 - 14:59:59 286 4.6% 0.08976.1 KB 4.1% 0.27
Sep 8 15:00:00 - 15:59:59 346 5.6% 0.101.1 MB 4.5% 0.30
Sep 8 16:00:00 - 16:59:59 364 5.9% 0.101.1 MB 4.6% 0.31
Sep 8 17:00:00 - 17:59:59 366 5.9% 0.101.7 MB 7.3% 0.49
Sep 8 18:00:00 - 18:59:59 337 5.5% 0.091.3 MB 5.6% 0.37
Sep 8 19:00:00 - 19:59:59 383 6.2% 0.111.8 MB 7.5% 0.50
Sep 8 20:00:00 - 20:59:59 313 5.1% 0.091.4 MB 6.0% 0.40
Sep 8 21:00:00 - 21:59:59 301 4.9% 0.081.1 MB 4.9% 0.33
Sep 8 22:00:00 - 22:59:59 308 5.0% 0.091.3 MB 5.4% 0.36
Sep 8 23:00:00 - 23:59:59 234 3.8% 0.071002.1 KB 4.2% 0.28
Sep 9 00:00:00 - 00:59:59 159 2.6% 0.04765.0 KB 3.2% 0.21
Sep 9 01:00:00 - 01:59:59 130 2.1% 0.04687.0 KB 2.9% 0.19
Sep 9 02:00:00 - 02:59:59 136 2.2% 0.04554.6 KB 2.3% 0.15
Sep 9 03:00:00 - 03:00:04 9 0.1% 2.258.2 KB 0.0% 2.04
TOTAL: 24:00:00 6181 100.0% 0.0723.5 MB 100.0% 0.28
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1108 337 0 0 0 0 0 0 771
2twilightnode-out.news.weretis.net 230 11 0 0 0 0 0 0 219
3newsfeed.endofthelinebbs.com 123 11 0 0 0 0 0 0 112
4news.tebibyte.org 1 1 0 0 0 0 0 0 0
TOTAL: 4 1462 360 0 0 0 0 0 0 1102

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 23
fido.ru.fidonet.today 7
fido.ru.mac 3
alt.history.ancient-egypt 2
local.general 2
oc.forsale 2
pt.ciencia.geral 2
alt.accounting 1
alt.antiques 1
alt.asshole.binky 1
alt.bbs.files-announce 1
alt.bin 1
alt.chess 1
alt.chinese 1
alt.clubs 1
alt.coupons 1
alt.crafts 1
alt.creative.writing 1
alt.culture 1
TOTAL: 39 360

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 680
EMP (phn path) 174
EMP (fsl) 121
Too many newsgroups 71
User-issued cancel 9
HTML Multipart 7
Too many newsgroups (meow) 1
TOTAL: 7 1063

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8261 2363 3698 87 15 6 28%23:59:52
2sklaffkom.se 6339 1089 1995 3046 27 1 17%23:59:56
3newsfeed.endofthelinebbs.com 10850 41 5758 35 0 314 0%23:59:56
4usenet.blueworldhosting.com 7876 4 4571 0 0 0 0%23:59:52
5weretis.net 1705 3 1627 0 0 0 0%23:55:50
TOTAL: 5 35031 3500 17649 3168 42 321 9%119:55:26
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org5.9 MB449.3 KB6.3 MB0.1 KB/s2.6 KB23:59:52
2sklaffkom.se1.9 MB14.7 MB16.5 MB0.2 KB/s4.1 KB23:59:56
3newsfeed.endofthelinebbs.com112.9 KB128.7 KB241.7 KB0.0 KB/s3.2 KB23:59:56
4usenet.blueworldhosting.com9.8 KB0.0 KB9.8 KB0.0 KB/s2.5 KB23:59:52
5weretis.net5.6 KB0.0 KB5.6 KB0.0 KB/s1.9 KB23:55:50
TOTAL: 57.9 MB15.2 MB23.1 MB0.1 KB/s3.5 KB119:55:26
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1227.224.132.66.censys-scanner.com 5 00.0 KB 0 0 000:00:15
2azpdes6i54ea.stretchoid.com 2 00.0 KB 0 0 000:00:01
316.5.0.244 1 00.0 KB 0 0 000:00:04
4193-176-31-216.infrawat.ch 1 00.0 KB 0 0 000:00:00
5198.235.24.244 1 00.0 KB 0 0 000:00:00
669-5-169-4.infrawat.ch 1 00.0 KB 0 0 000:00:00
7o033.scanner.modat.io 1 00.0 KB 0 0 000:00:00
8r3-170-aa.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 8 13 00.0 KB 0 0 000:00:24

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 5 00.0 KB 0 0 000:00:15
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.stretchoid.com 2 00.0 KB 0 0 000:00:01
4unresolved 2 00.0 KB 0 0 000:00:04
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
6*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 6 13 00.0 KB 0 0 000:00:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
227.224.132.66.censys-scanner.com 0.057 0.043 0.00000:00:15
16.5.0.244 0.006 0.017 0.00000:00:04
r3-170-aa.monitoring.internet-measurement.com 0.006 0.015 0.00000:00:01
azpdes6i54ea.stretchoid.com 0.005 0.018 0.00000:00:01
o033.scanner.modat.io 0.005 0.015 0.00000:00:00
198.235.24.244 0.002 0.017 0.00000:00:00
69-5-169-4.infrawat.ch 0.004 0.005 0.00000:00:00
193-176-31-216.infrawat.ch 0.002 0.011 0.00000:00:00
TOTAL: 8 0.087 0.141 0.00000:00:24

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

SystemConn
227.224.132.66.censys-scanner.com 2
o033.scanner.modat.io 2
azpdes6i54ea.stretchoid.com 1
TOTAL: 3 5

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

CommandCount
#026#003#001 1
EHLO www.censys.io 1
FEAT 1
MGLNDD_172.234.115.63_119 1
SYST 1
TOTAL: 5 5

NNRP client timeouts [Top 20]:

SystemConnPeer
227.224.132.66.censys-scanner.com 1 3
69-5-169-4.infrawat.ch 1 1
TOTAL: 2 2 6