Daily Usenet report

Sep 7 03:00:04 -- Sep 8 03:00:03

Unknown entries from news log file:

First 50 / 126 lines (39.7%)

Sep 7 03:01:03 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 7 03:42:32 twilightnode nnrpd[2940736]: Reading access from /usr/local/news/etc/readers.conf
Sep 7 03:42:32 twilightnode nnrpd[2940736]: Auth strategy 'localhost' does not match client. Removing.
Sep 7 03:42:32 twilightnode nnrpd[2940736]: o044.scanner.modat.io res <anonymous>
Sep 7 03:42:32 twilightnode nnrpd[2940736]: o044.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 7 03:42:32 twilightnode nnrpd[2940736]: o044.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 7 03:42:32 twilightnode nnrpd[2940736]: o044.scanner.modat.io match_user <anonymous> <anonymous>
Sep 7 03:42:32 twilightnode nnrpd[2940736]: o044.scanner.modat.io no_post anonymous-read
Sep 7 09:46:26 twilightnode nnrpd[2957737]: Reading access from /usr/local/news/etc/readers.conf
Sep 7 09:46:26 twilightnode nnrpd[2957737]: Auth strategy 'localhost' does not match client. Removing.
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com res <anonymous>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com no_post anonymous-read
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com res <anonymous>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 7 09:46:26 twilightnode nnrpd[2957737]: 132.172.132.66.censys-scanner.com no_post anonymous-read
Sep 7 11:43:27 twilightnode nnrpd[2962978]: Reading access from /usr/local/news/etc/readers.conf
Sep 7 11:43:27 twilightnode nnrpd[2962978]: Auth strategy 'localhost' does not match client. Removing.
Sep 7 11:43:27 twilightnode nnrpd[2962978]: 94.154.43.21 res <anonymous>
Sep 7 11:43:27 twilightnode nnrpd[2962978]: 94.154.43.21 no_match_user <anonymous> <localhost>
Sep 7 11:43:27 twilightnode nnrpd[2962978]: 94.154.43.21 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 7 11:43:27 twilightnode nnrpd[2962978]: 94.154.43.21 match_user <anonymous> <anonymous>
Sep 7 11:43:27 twilightnode nnrpd[2962978]: 94.154.43.21 no_post anonymous-read
Sep 7 15:09:05 twilightnode nnrpd[2972894]: Reading access from /usr/local/news/etc/readers.conf
Sep 7 15:09:05 twilightnode nnrpd[2972894]: Auth strategy 'localhost' does not match client. Removing.
Sep 7 15:09:05 twilightnode nnrpd[2972894]: 16.5.0.244 res <anonymous>
Sep 7 15:09:05 twilightnode nnrpd[2972894]: 16.5.0.244 no_match_user <anonymous> <localhost>
Sep 7 15:09:05 twilightnode nnrpd[2972894]: 16.5.0.244 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 7 15:09:05 twilightnode nnrpd[2972894]: 16.5.0.244 match_user <anonymous> <anonymous>
Sep 7 15:09:05 twilightnode nnrpd[2972894]: 16.5.0.244 no_post anonymous-read
Sep 7 15:36:43 twilightnode nnrpd[2974002]: Reading access from /usr/local/news/etc/readers.conf
Sep 7 15:36:43 twilightnode nnrpd[2974002]: Auth strategy 'localhost' does not match client. Removing.
Sep 7 15:36:43 twilightnode nnrpd[2974002]: 69-5-169-46.infrawat.ch res <anonymous>
Sep 7 15:36:43 twilightnode nnrpd[2974002]: 69-5-169-46.infrawat.ch no_match_user <anonymous> <localhost>
Sep 7 15:36:43 twilightnode nnrpd[2974002]: 69-5-169-46.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 7 15:36:43 twilightnode nnrpd[2974002]: 69-5-169-46.infrawat.ch match_user <anonymous> <anonymous>
Sep 7 15:36:43 twilightnode nnrpd[2974002]: 69-5-169-46.infrawat.ch no_post anonymous-read
Sep 7 15:36:48 twilightnode nnrpd[2974003]: Reading access from /usr/local/news/etc/readers.conf
Sep 7 15:36:48 twilightnode nnrpd[2974003]: Auth strategy 'localhost' does not match client. Removing.
Sep 7 15:36:48 twilightnode nnrpd[2974003]: 193-124-20-228.infrawat.ch res <anonymous>
Sep 7 15:36:48 twilightnode nnrpd[2974003]: 193-124-20-228.infrawat.ch no_match_user <anonymous> <localhost>
Sep 7 15:36:48 twilightnode nnrpd[2974003]: 193-124-20-228.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 7 15:36:48 twilightnode nnrpd[2974003]: 193-124-20-228.infrawat.ch match_user <anonymous> <anonymous>
Sep 7 15:36:48 twilightnode nnrpd[2974003]: 193-124-20-228.infrawat.ch no_post anonymous-read
Sep 7 16:18:06 twilightnode nnrpd[2975984]: Reading access from /usr/local/news/etc/readers.conf
Sep 7 16:18:06 twilightnode nnrpd[2975984]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7285 54.8%1.3 MB 60.5%
innd 4551 34.3%637.1 KB 29.6%
innfeed 1257 9.5%193.1 KB 9.0%
nnrpd 192 1.4%20.1 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 13286 100.0%2.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19820 52.6%
Negative hits 10648 28.2%
Do not exist 7227 19.2%
Cache misses 1 0.0%
TOTAL: 4 37696 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.124 0.0%34 0.000 3.647 28.000
article cleanup00:00:00.094 0.0%7243 0.000 0.013 0.091
article logging00:00:00.216 0.0%7288 0.000 0.030 0.182
article parse00:00:00.159 0.0%9775 0.000 0.016 0.102
article write00:00:06.494 0.0%6074 0.609 1.069 3.615
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.247 0.0%39437 0.000 0.006 0.043
hisgrep/artcncl00:00:00.009 0.0%34 0.000 0.265 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.277 0.0%37792 0.060 0.113 0.250
history sync00:00:00.047 0.0%317 0.000 0.148 2.500
history write00:00:03.332 0.0%7243 0.100 0.460 1.075
idle23:59:48.107 99.9%40638 1068.661 2125.796 9526.095
nntp read00:00:00.805 0.0%39654 0.000 0.020 0.161
overview write00:00:04.288 0.0%6074 0.250 0.706 2.250
perl filter00:00:13.852 0.0%7243 0.809 1.912 8.346
python filter00:00:01.029 0.0%7243 0.000 0.142 1.037
site send00:00:00.282 0.0%12119 0.000 0.023 0.102
TOTAL: 1824:00:23.362100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.041 0.0%6327 0.000 0.006 0.212
article prepare00:00:00.005 0.0%25824 0.000 0.000 0.006
article read00:00:00.960 0.0%4099 0.000 0.234 1.727
backlog stats00:00:00.083 0.0%81179 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.549 0.0%45452 0.000 0.012 0.051
data write00:00:01.037 0.0%39567 0.000 0.026 0.100
idle23:59:28.964100.0%81179 376.519 1063.932 6672.978
status file00:00:00.159 0.0%163 0.000 0.975 3.000
TOTAL: 923:59:31.798100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:22.994100.0%26 2.000 884.385 10042.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%114 0.000 0.000 0.000
TOTAL: 300:00:22.994100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 9881 4569 5258 54 46%41:58:13
2usenet.blueworldhosting.com 716 7187 902 5324 961 12%23:48:03
3news.tebibyte.org 2 6105 250 5839 16 4%39:41:27
4newsfeed.endofthelinebbs.com 2 3815 160 3574 81 4%18:15:08
5news.sklaffkom.se 32 61 54 7 0 88%07:56:27
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 782 27049 5935 20002 1112 21%131:39:18
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.4 MB0.0 KB102.3 KB13.5 MB 99%3.0 KB
2usenet.blueworldhosting.com5.1 MB0.0 KB1.3 MB6.4 MB 80%3.5 KB
3newsfeed.endofthelinebbs.com2.1 MB0.0 KB126.4 KB2.2 MB 94%9.4 KB
4news.tebibyte.org892.5 KB52.6 KB7.8 KB952.9 KB 93%3.6 KB
5news.sklaffkom.se192.9 KB0.0 KB0.0 KB192.9 KB100%3.6 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 621.6 MB52.6 KB1.5 MB23.2 MB 93%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 7 03:00:04 - 03:59:59 74 1.2% 0.02335.8 KB 1.4% 0.09
Sep 7 04:00:00 - 04:59:59 139 2.3% 0.04791.0 KB 3.4% 0.22
Sep 7 05:00:00 - 05:59:59 85 1.4% 0.02330.7 KB 1.4% 0.09
Sep 7 06:00:00 - 06:59:59 138 2.3% 0.04569.8 KB 2.5% 0.16
Sep 7 07:00:00 - 07:59:59 164 2.7% 0.05660.0 KB 2.8% 0.18
Sep 7 08:00:00 - 08:59:59 275 4.5% 0.081.0 MB 4.4% 0.28
Sep 7 09:00:00 - 09:59:59 459 7.6% 0.131.7 MB 7.7% 0.50
Sep 7 10:00:00 - 10:59:59 293 4.8% 0.08893.5 KB 3.9% 0.25
Sep 7 11:00:00 - 11:59:59 313 5.2% 0.09908.3 KB 3.9% 0.25
Sep 7 12:00:00 - 12:59:59 331 5.5% 0.09986.1 KB 4.3% 0.27
Sep 7 13:00:00 - 13:59:59 282 4.6% 0.081.0 MB 4.5% 0.29
Sep 7 14:00:00 - 14:59:59 302 5.0% 0.081.2 MB 5.4% 0.35
Sep 7 15:00:00 - 15:59:59 353 5.8% 0.101.2 MB 5.2% 0.34
Sep 7 16:00:00 - 16:59:59 362 6.0% 0.101.4 MB 6.1% 0.39
Sep 7 17:00:00 - 17:59:59 326 5.4% 0.091.5 MB 6.6% 0.42
Sep 7 18:00:00 - 18:59:59 320 5.3% 0.091.1 MB 4.7% 0.30
Sep 7 19:00:00 - 19:59:59 269 4.4% 0.071.3 MB 5.6% 0.36
Sep 7 20:00:00 - 20:59:59 328 5.4% 0.091.0 MB 4.5% 0.29
Sep 7 21:00:00 - 21:59:59 280 4.6% 0.081013.3 KB 4.4% 0.28
Sep 7 22:00:00 - 22:59:59 267 4.4% 0.071.3 MB 5.7% 0.37
Sep 7 23:00:00 - 23:59:59 214 3.5% 0.06861.9 KB 3.7% 0.24
Sep 8 00:00:00 - 00:59:59 152 2.5% 0.04565.7 KB 2.4% 0.16
Sep 8 01:00:00 - 01:59:59 184 3.0% 0.05643.8 KB 2.8% 0.18
Sep 8 02:00:00 - 02:59:59 149 2.5% 0.04610.5 KB 2.6% 0.17
Sep 8 03:00:00 - 03:00:03 12 0.2% 4.0020.7 KB 0.1% 6.89
TOTAL: 23:59:59 6071 100.0% 0.0722.6 MB 100.0% 0.27
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 961 348 0 0 0 0 0 0 613
2twilightnode-out.news.weretis.net 141 7 0 0 0 0 0 0 134
3newsfeed.endofthelinebbs.com 109 17 0 0 0 0 0 0 92
4news.tebibyte.org 3 0 0 0 0 0 0 0 3
TOTAL: 4 1214 372 0 0 0 0 0 0 842

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 45
fido.ru.fidonet.today 8
kraft.talks 4
fido.8912.local 3
fido.ru.space 3
local.general 3
fido.r50.sysop.drunk 2
fido.ru.mac 2
spline.test 2
ak.test 1
alive.test 1
alt.asshole.binky 1
alt.bbs.files-announce 1
alt.test.hello-world 1
bc.test 1
chmurka.statystyki 1
fido.ru.golded 1
fido.su.pol 1
france.basse-normandie 1
TOTAL: 22 372

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 614
Too many newsgroups 94
EMP (phn path) 57
User-issued cancel 30
Binary: misplaced binary 1
HTML Multipart 1
TOTAL: 6 797

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7817 2278 3610 131 16 57 29%23:59:49
2sklaffkom.se 6221 996 2061 2960 24 10 16%23:59:53
3newsfeed.endofthelinebbs.com 10680 53 5747 15 0 197 0%23:59:49
4usenet.blueworldhosting.com 8077 3 4761 0 0 45 0%23:59:56
5weretis.net 1540 3 1454 0 0 0 0%23:56:26
TOTAL: 5 34335 3333 17633 3106 40 309 9%119:55:53
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org5.9 MB694.3 KB6.5 MB0.1 KB/s2.8 KB23:59:49
2sklaffkom.se1.6 MB13.7 MB15.2 MB0.2 KB/s3.9 KB23:59:53
3newsfeed.endofthelinebbs.com250.6 KB53.5 KB304.1 KB0.0 KB/s4.5 KB23:59:49
4usenet.blueworldhosting.com28.9 KB0.0 KB28.9 KB0.0 KB/s9.6 KB23:59:56
5weretis.net3.7 KB0.0 KB3.7 KB0.0 KB/s1.2 KB23:56:26
TOTAL: 57.7 MB14.4 MB22.1 MB0.1 KB/s3.5 KB119:55:53
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1104.218.165.188 3 00.0 KB 0 0 000:00:10
263.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:01
3azpdestraplb.stretchoid.com 2 00.0 KB 0 0 000:00:00
4132.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
516.5.0.244 1 00.0 KB 0 0 000:00:03
6193-124-20-228.infrawat.ch 1 00.0 KB 0 0 000:00:00
7216.226.77.30 1 00.0 KB 0 0 000:00:00
869-5-169-46.infrawat.ch 1 00.0 KB 0 0 000:00:00
994.154.43.21 1 00.0 KB 0 0 000:00:01
10o044.scanner.modat.io 1 00.0 KB 0 0 000:00:00
11r3-206-ce.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 11 16 00.0 KB 0 0 000:00:22

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

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

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
104.218.165.188 0.014 0.025 0.00000:00:10
16.5.0.244 0.002 0.011 0.00000:00:03
r3-206-ce.monitoring.internet-measurement.com 0.003 0.018 0.00000:00:01
132.172.132.66.censys-scanner.com 0.025 0.020 0.00000:00:01
63.146.94.167.censys-scanner.com 0.036 0.022 0.00000:00:01
94.154.43.21 0.006 0.011 0.00000:00:01
o044.scanner.modat.io 0.005 0.016 0.00000:00:00
azpdestraplb.stretchoid.com 0.009 0.018 0.00000:00:00
216.226.77.30 0.004 0.012 0.00000:00:00
69-5-169-46.infrawat.ch 0.006 0.011 0.00000:00:00
193-124-20-228.infrawat.ch 0.006 0.003 0.00000:00:00
TOTAL: 11 0.116 0.167 0.00000:00:22

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

SystemConn
104.218.165.188 2
o044.scanner.modat.io 2
132.172.132.66.censys-scanner.com 1
63.146.94.167.censys-scanner.com 1
azpdestraplb.stretchoid.com 1
TOTAL: 5 7

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

CommandCount
EHLO www.censys.io 2
#026#003#001 1
?#024 1
FEAT 1
MGLNDD_172.234.115.63_119 1
SYST 1
TOTAL: 6 7

NNRP client timeouts [Top 20]:

SystemConnPeer
193-124-20-228.infrawat.ch 1 1
63.146.94.167.censys-scanner.com 1 2
TOTAL: 2 2 4
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