Daily Usenet report

Sep 21 03:00:04 -- Sep 22 03:00:04

Unknown entries from news log file:

First 50 / 70 lines (71.4%)

Sep 21 03:01:13 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 21 09:39:30 twilightnode nnrpd[3922970]: Reading access from /usr/local/news/etc/readers.conf
Sep 21 09:39:30 twilightnode nnrpd[3922970]: Auth strategy 'localhost' does not match client. Removing.
Sep 21 09:39:30 twilightnode nnrpd[3922970]: azpdesrr7q7i.stretchoid.com res <anonymous>
Sep 21 09:39:30 twilightnode nnrpd[3922970]: azpdesrr7q7i.stretchoid.com no_match_user <anonymous> <localhost>
Sep 21 09:39:30 twilightnode nnrpd[3922970]: azpdesrr7q7i.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 21 09:39:30 twilightnode nnrpd[3922970]: azpdesrr7q7i.stretchoid.com match_user <anonymous> <anonymous>
Sep 21 09:39:30 twilightnode nnrpd[3922970]: azpdesrr7q7i.stretchoid.com no_post anonymous-read
Sep 21 09:39:41 twilightnode nnrpd[3922971]: Reading access from /usr/local/news/etc/readers.conf
Sep 21 09:39:41 twilightnode nnrpd[3922971]: Auth strategy 'localhost' does not match client. Removing.
Sep 21 09:39:41 twilightnode nnrpd[3922971]: azpdesrr7q7i.stretchoid.com res <anonymous>
Sep 21 09:39:41 twilightnode nnrpd[3922971]: azpdesrr7q7i.stretchoid.com no_match_user <anonymous> <localhost>
Sep 21 09:39:41 twilightnode nnrpd[3922971]: azpdesrr7q7i.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 21 09:39:41 twilightnode nnrpd[3922971]: azpdesrr7q7i.stretchoid.com match_user <anonymous> <anonymous>
Sep 21 09:39:41 twilightnode nnrpd[3922971]: azpdesrr7q7i.stretchoid.com no_post anonymous-read
Sep 21 10:13:08 twilightnode nnrpd[3924613]: Reading access from /usr/local/news/etc/readers.conf
Sep 21 10:13:08 twilightnode nnrpd[3924613]: Auth strategy 'localhost' does not match client. Removing.
Sep 21 10:13:08 twilightnode nnrpd[3924613]: 61.146.94.167.censys-scanner.com res <anonymous>
Sep 21 10:13:08 twilightnode nnrpd[3924613]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 21 10:13:08 twilightnode nnrpd[3924613]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 21 10:13:08 twilightnode nnrpd[3924613]: 61.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Sep 21 10:13:08 twilightnode nnrpd[3924613]: 61.146.94.167.censys-scanner.com no_post anonymous-read
Sep 21 10:13:11 twilightnode nnrpd[3924614]: Reading access from /usr/local/news/etc/readers.conf
Sep 21 10:13:11 twilightnode nnrpd[3924614]: Auth strategy 'localhost' does not match client. Removing.
Sep 21 10:13:11 twilightnode nnrpd[3924614]: 61.146.94.167.censys-scanner.com res <anonymous>
Sep 21 10:13:11 twilightnode nnrpd[3924614]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 21 10:13:11 twilightnode nnrpd[3924614]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 21 10:13:11 twilightnode nnrpd[3924614]: 61.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Sep 21 10:13:11 twilightnode nnrpd[3924614]: 61.146.94.167.censys-scanner.com no_post anonymous-read
Sep 21 10:13:16 twilightnode nnrpd[3924615]: Reading access from /usr/local/news/etc/readers.conf
Sep 21 10:13:16 twilightnode nnrpd[3924615]: Auth strategy 'localhost' does not match client. Removing.
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com res <anonymous>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com no_post anonymous-read
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com res <anonymous>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Sep 21 10:13:16 twilightnode nnrpd[3924615]: 61.146.94.167.censys-scanner.com no_post anonymous-read
Sep 21 11:18:23 twilightnode nnrpd[3928251]: Reading access from /usr/local/news/etc/readers.conf
Sep 21 11:18:23 twilightnode nnrpd[3928251]: Auth strategy 'localhost' does not match client. Removing.
Sep 21 11:18:23 twilightnode nnrpd[3928251]: 194.187.176.46 res <anonymous>
Sep 21 11:18:23 twilightnode nnrpd[3928251]: 194.187.176.46 no_match_user <anonymous> <localhost>
Sep 21 11:18:23 twilightnode nnrpd[3928251]: 194.187.176.46 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 21 11:18:23 twilightnode nnrpd[3928251]: 194.187.176.46 match_user <anonymous> <anonymous>
Sep 21 11:18:23 twilightnode nnrpd[3928251]: 194.187.176.46 no_post anonymous-read
Sep 21 14:01:47 twilightnode nnrpd[3937062]: Reading access from /usr/local/news/etc/readers.conf
Sep 21 14:01:47 twilightnode nnrpd[3937062]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7440 56.2%1.3 MB 61.5%
innd 4423 33.4%629.0 KB 28.9%
innfeed 1255 9.5%195.0 KB 8.9%
nnrpd 106 0.8%11.2 KB 0.5%
controlchan 14 0.1%3.2 KB 0.1%
pgpverify 1 0.0%0.3 KB 0.0%
TOTAL: 6 13239 100.0%2.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19852 51.7%
Negative hits 11179 29.1%
Do not exist 7395 19.2%
Cache misses 1 0.0%
TOTAL: 4 38427 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.019 0.0%19 0.000 1.000 10.000
article cleanup00:00:00.081 0.0%7415 0.000 0.011 0.609
article logging00:00:00.270 0.0%7441 0.000 0.036 0.312
article parse00:00:00.121 0.0%10419 0.000 0.012 0.056
article write00:00:06.381 0.0%6356 0.472 1.004 1.857
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.272 0.0%40890 0.000 0.007 0.032
hisgrep/artcncl00:00:00.010 0.0%19 0.000 0.526 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.406 0.0%38550 0.062 0.114 0.241
history sync00:00:00.054 0.0%339 0.000 0.159 1.333
history write00:00:03.174 0.0%7416 0.000 0.428 0.821
idle23:59:50.277 99.9%41651 1006.445 2074.147 6522.935
nntp read00:00:00.890 0.0%40670 0.003 0.022 0.083
overview write00:00:04.445 0.0%6356 0.259 0.699 2.214
perl filter00:00:11.765 0.0%7415 0.863 1.587 9.438
python filter00:00:00.983 0.0%7415 0.016 0.133 0.744
site send00:00:00.304 0.0%12653 0.000 0.024 0.111
TOTAL: 1824:00:23.452100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%6667 0.000 0.003 0.059
article prepare00:00:00.005 0.0%27336 0.000 0.000 0.010
article read00:00:00.949 0.0%4386 0.000 0.216 3.000
backlog stats00:00:00.044 0.0%82300 0.000 0.001 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.596 0.0%46555 0.000 0.013 0.043
data write00:00:00.950 0.0%40508 0.000 0.023 0.067
idle23:59:48.833100.0%82300 385.799 1049.682 4277.369
status file00:00:00.132 0.0%162 0.000 0.815 3.000
TOTAL: 923:59:51.527100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:27.010 99.8%16 65.000 1688.125 10767.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.067 0.2%5192 0.000 0.013 0.111
TOTAL: 300:00:27.077100.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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
nl-admin@nic.surfnet.nl001101
TOTAL001101

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 10974 4478 6388 108 40%46:10:00
2usenet.blueworldhosting.com 721 7342 1314 5147 881 17%23:51:14
3newsfeed.endofthelinebbs.com 2 3576 183 3334 59 5%17:54:07
4news.tebibyte.org 1 3451 181 3268 2 5%23:59:55
5news.sklaffkom.se 32 74 55 19 0 74%08:14:08
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 785 25417 6211 18156 1050 24%120:09:24
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.3 MB0.0 KB342.3 KB14.6 MB 97%3.3 KB
2usenet.blueworldhosting.com6.7 MB0.0 KB1.1 MB7.8 MB 86%3.6 KB
3newsfeed.endofthelinebbs.com3.2 MB0.0 KB107.0 KB3.3 MB 96%13.9 KB
4news.tebibyte.org784.0 KB0.0 KB4.1 KB788.1 KB 99%4.3 KB
5news.sklaffkom.se167.7 KB0.0 KB0.0 KB167.7 KB100%3.0 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 625.2 MB0.0 KB1.5 MB26.7 MB 94%3.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 21 03:00:04 - 03:59:59 90 1.4% 0.03283.4 KB 1.1% 0.08
Sep 21 04:00:00 - 04:59:59 121 1.9% 0.03533.3 KB 2.0% 0.15
Sep 21 05:00:00 - 05:59:59 99 1.6% 0.03424.1 KB 1.6% 0.12
Sep 21 06:00:00 - 06:59:59 123 1.9% 0.03408.5 KB 1.5% 0.11
Sep 21 07:00:00 - 07:59:59 144 2.3% 0.04455.2 KB 1.7% 0.13
Sep 21 08:00:00 - 08:59:59 190 3.0% 0.05658.6 KB 2.5% 0.18
Sep 21 09:00:00 - 09:59:59 324 5.1% 0.091.1 MB 4.3% 0.32
Sep 21 10:00:00 - 10:59:59 334 5.3% 0.091.2 MB 4.5% 0.33
Sep 21 11:00:00 - 11:59:59 316 5.0% 0.091.7 MB 6.4% 0.47
Sep 21 12:00:00 - 12:59:59 387 6.1% 0.111.3 MB 5.1% 0.37
Sep 21 13:00:00 - 13:59:59 350 5.5% 0.101.2 MB 4.6% 0.34
Sep 21 14:00:00 - 14:59:59 270 4.2% 0.07840.2 KB 3.2% 0.23
Sep 21 15:00:00 - 15:59:59 421 6.6% 0.122.2 MB 8.6% 0.63
Sep 21 16:00:00 - 16:59:59 328 5.2% 0.091.2 MB 4.5% 0.33
Sep 21 17:00:00 - 17:59:59 351 5.5% 0.101.6 MB 6.3% 0.46
Sep 21 18:00:00 - 18:59:59 372 5.9% 0.101.4 MB 5.2% 0.38
Sep 21 19:00:00 - 19:59:59 324 5.1% 0.091.2 MB 4.8% 0.36
Sep 21 20:00:00 - 20:59:59 352 5.5% 0.101.5 MB 5.7% 0.42
Sep 21 21:00:00 - 21:59:59 330 5.2% 0.091.2 MB 4.5% 0.33
Sep 21 22:00:00 - 22:59:59 283 4.5% 0.082.0 MB 7.7% 0.56
Sep 21 23:00:00 - 23:59:59 236 3.7% 0.071.1 MB 4.1% 0.30
Sep 22 00:00:00 - 00:59:59 234 3.7% 0.07861.0 KB 3.3% 0.24
Sep 22 01:00:00 - 01:59:59 194 3.1% 0.05829.8 KB 3.1% 0.23
Sep 22 02:00:00 - 02:59:59 173 2.7% 0.05948.2 KB 3.6% 0.26
Sep 22 03:00:00 - 03:00:04 9 0.1% 2.258.9 KB 0.0% 2.22
TOTAL: 24:00:00 6355 100.0% 0.0725.8 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 881 355 0 0 0 0 0 0 526
2twilightnode-out.news.weretis.net 134 6 0 0 0 0 0 0 128
3newsfeed.endofthelinebbs.com 70 7 0 0 0 0 0 0 63
TOTAL: 3 1085 368 0 0 0 0 0 0 717

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 25
fido.su.tormoz 20
fido.su.pol 6
hispagatos.mastodon.tag.hacking 6
fido.ru.home 5
newsreader.test 3
fido.8912.local 2
fr.comp.securite 2
local.general 2
alt.bbs.files-announce 1
alt.test.9 1
chmurka.statystyki 1
fido.golded 1
fido.pushkin.local 1
kraft.talks 1
kraft.vehicles 1
pl.listserv.chomor-l 1
realms.bbs 1
TOTAL: 19 368

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 473
Too many newsgroups 114
EMP (phn path) 96
HTML Multipart 3
User-issued cancel 3
Too many newsgroups (low_xpost) 1
TOTAL: 6 690

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 25
newsfeed.endofthelinebbs.com 1
TOTAL: 2 26
No colon-space in header field
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 2 27

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8433 2354 3801 140 0 54 27%23:59:54
2sklaffkom.se 6439 919 2024 3349 0 5 14%23:59:54
3newsfeed.endofthelinebbs.com 10679 29 5890 33 0 298 0%23:59:54
4weretis.net 1905 8 1817 0 0 0 0%23:56:02
5usenet.blueworldhosting.com 7550 4 4582 0 0 25 0%23:59:37
TOTAL: 5 35006 3314 18114 3522 0 382 9%119:55:21
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.0 MB1.7 MB7.7 MB0.1 KB/s3.2 KB23:59:54
2sklaffkom.se1.5 MB16.5 MB18.0 MB0.2 KB/s4.3 KB23:59:54
3newsfeed.endofthelinebbs.com109.2 KB76.3 KB185.5 KB0.0 KB/s3.0 KB23:59:54
4weretis.net21.7 KB0.0 KB21.7 KB0.0 KB/s2.7 KB23:56:02
5usenet.blueworldhosting.com18.4 KB0.0 KB18.4 KB0.0 KB/s4.6 KB23:59:37
TOTAL: 57.7 MB18.2 MB25.9 MB0.1 KB/s3.9 KB119:55:21
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
161.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:01
2azpdesrr7q7i.stretchoid.com 2 00.0 KB 0 0 000:00:10
316.5.0.239 1 00.0 KB 0 0 000:00:03
4194.187.176.46 1 00.0 KB 0 0 000:00:06
5elliemay.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
6xanthe.probe.onyphe.net 1 00.0 KB 0 0 000:00:01
TOTAL: 6 9 00.0 KB 0 0 000:00:27

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:01
2*.probe.onyphe.net 2 00.0 KB 0 0 000:00:04
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4unresolved 2 00.0 KB 0 0 000:00:10
TOTAL: 4 9 00.0 KB 0 0 000:00:27

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdesrr7q7i.stretchoid.com 0.009 0.019 0.00000:00:10
194.187.176.46 0.006 0.016 0.00000:00:06
16.5.0.239 0.005 0.011 0.00000:00:03
elliemay.probe.onyphe.net 0.005 0.004 0.00000:00:02
61.146.94.167.censys-scanner.com 0.036 0.022 0.00000:00:01
xanthe.probe.onyphe.net 0.005 0.011 0.00000:00:01
TOTAL: 6 0.066 0.083 0.00000:00:27

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

SystemConn
xanthe.probe.onyphe.net 2
61.146.94.167.censys-scanner.com 1
azpdesrr7q7i.stretchoid.com 1
TOTAL: 3 4

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

CommandCount
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
61.146.94.167.censys-scanner.com 1 2
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