Daily Usenet report

Sep 6 03:00:03 -- Sep 7 03:00:03

Unknown entries from news log file:

First 50 / 95 lines (52.6%)

Sep 6 03:00:16 twilightnode nnrpd[2872386]: Reading access from /usr/local/news/etc/readers.conf
Sep 6 03:00:16 twilightnode nnrpd[2872386]: Auth strategy 'localhost' does not match client. Removing.
Sep 6 03:00:16 twilightnode nnrpd[2872386]: o004.scanner.modat.io res <anonymous>
Sep 6 03:00:16 twilightnode nnrpd[2872386]: o004.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 6 03:00:16 twilightnode nnrpd[2872386]: o004.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 6 03:00:16 twilightnode nnrpd[2872386]: o004.scanner.modat.io match_user <anonymous> <anonymous>
Sep 6 03:00:16 twilightnode nnrpd[2872386]: o004.scanner.modat.io no_post anonymous-read
Sep 6 03:01:02 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 6 07:23:37 twilightnode nnrpd[2884519]: Reading access from /usr/local/news/etc/readers.conf
Sep 6 07:23:37 twilightnode nnrpd[2884519]: Auth strategy 'localhost' does not match client. Removing.
Sep 6 07:23:37 twilightnode nnrpd[2884519]: 194-88-98-88.infrawat.ch res <anonymous>
Sep 6 07:23:37 twilightnode nnrpd[2884519]: 194-88-98-88.infrawat.ch no_match_user <anonymous> <localhost>
Sep 6 07:23:37 twilightnode nnrpd[2884519]: 194-88-98-88.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 6 07:23:37 twilightnode nnrpd[2884519]: 194-88-98-88.infrawat.ch match_user <anonymous> <anonymous>
Sep 6 07:23:37 twilightnode nnrpd[2884519]: 194-88-98-88.infrawat.ch no_post anonymous-read
Sep 6 07:32:28 twilightnode nnrpd[2884941]: Reading access from /usr/local/news/etc/readers.conf
Sep 6 07:32:28 twilightnode nnrpd[2884941]: Auth strategy 'localhost' does not match client. Removing.
Sep 6 07:32:28 twilightnode nnrpd[2884941]: 213-166-84-49.infrawat.ch res <anonymous>
Sep 6 07:32:28 twilightnode nnrpd[2884941]: 213-166-84-49.infrawat.ch no_match_user <anonymous> <localhost>
Sep 6 07:32:28 twilightnode nnrpd[2884941]: 213-166-84-49.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 6 07:32:28 twilightnode nnrpd[2884941]: 213-166-84-49.infrawat.ch match_user <anonymous> <anonymous>
Sep 6 07:32:28 twilightnode nnrpd[2884941]: 213-166-84-49.infrawat.ch no_post anonymous-read
Sep 6 07:36:41 twilightnode nnrpd[2885204]: Reading access from /usr/local/news/etc/readers.conf
Sep 6 07:36:41 twilightnode nnrpd[2885204]: Auth strategy 'localhost' does not match client. Removing.
Sep 6 07:36:41 twilightnode nnrpd[2885204]: 89.195.132.66.censys-scanner.com res <anonymous>
Sep 6 07:36:41 twilightnode nnrpd[2885204]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 6 07:36:41 twilightnode nnrpd[2885204]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 6 07:36:41 twilightnode nnrpd[2885204]: 89.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 6 07:36:41 twilightnode nnrpd[2885204]: 89.195.132.66.censys-scanner.com no_post anonymous-read
Sep 6 07:36:46 twilightnode nnrpd[2885207]: Reading access from /usr/local/news/etc/readers.conf
Sep 6 07:36:46 twilightnode nnrpd[2885207]: Auth strategy 'localhost' does not match client. Removing.
Sep 6 07:36:46 twilightnode nnrpd[2885207]: 89.195.132.66.censys-scanner.com res <anonymous>
Sep 6 07:36:46 twilightnode nnrpd[2885207]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 6 07:36:46 twilightnode nnrpd[2885207]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 6 07:36:46 twilightnode nnrpd[2885207]: 89.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 6 07:36:46 twilightnode nnrpd[2885207]: 89.195.132.66.censys-scanner.com no_post anonymous-read
Sep 6 07:36:51 twilightnode nnrpd[2885208]: Reading access from /usr/local/news/etc/readers.conf
Sep 6 07:36:51 twilightnode nnrpd[2885208]: Auth strategy 'localhost' does not match client. Removing.
Sep 6 07:36:51 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com res <anonymous>
Sep 6 07:36:51 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 6 07:36:51 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 6 07:36:51 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 6 07:36:51 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com no_post anonymous-read
Sep 6 07:36:52 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com res <anonymous>
Sep 6 07:36:52 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 6 07:36:52 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 6 07:36:52 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 6 07:36:52 twilightnode nnrpd[2885208]: 89.195.132.66.censys-scanner.com no_post anonymous-read
Sep 6 08:02:33 twilightnode nnrpd[2886265]: Reading access from /usr/local/news/etc/readers.conf
Sep 6 08:02:33 twilightnode nnrpd[2886265]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7362 55.3%1.3 MB 60.7%
innd 4484 33.7%629.4 KB 29.3%
innfeed 1313 9.9%198.8 KB 9.2%
nnrpd 153 1.1%15.7 KB 0.7%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 13313 100.0%2.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20056 55.3%
Negative hits 9110 25.1%
Do not exist 7075 19.5%
Cache misses 53 0.1%
TOTAL: 4 36294 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.048 0.0%17 0.000 2.824 24.000
article cleanup00:00:00.083 0.0%7295 0.000 0.011 0.308
article logging00:00:00.257 0.0%7361 0.000 0.035 0.152
article parse00:00:00.112 0.0%9375 0.000 0.012 0.143
article write00:00:06.309 0.0%5945 0.379 1.061 4.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.138 0.0%37746 0.000 0.004 0.034
hisgrep/artcncl00:00:00.004 0.0%17 0.000 0.235 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.099 0.0%36392 0.048 0.113 0.272
history sync00:00:00.047 0.0%348 0.000 0.135 1.000
history write00:00:03.102 0.0%7095 0.125 0.437 0.889
idle24:00:07.188 99.9%38785 1126.882 2227.851 8572.757
nntp read00:00:00.772 0.0%37812 0.000 0.020 0.222
overview write00:00:03.894 0.0%5945 0.172 0.655 2.357
perl filter00:00:12.570 0.0%7095 0.754 1.772 7.000
python filter00:00:00.715 0.0%7095 0.000 0.101 0.639
site send00:00:00.269 0.0%11861 0.000 0.023 0.082
TOTAL: 1824:00:39.607100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.010 0.0%6648 0.000 0.002 0.077
article prepare00:00:00.003 0.0%25728 0.000 0.000 0.015
article read00:00:01.012 0.0%4146 0.000 0.244 2.500
backlog stats00:00:00.096 0.0%80504 0.000 0.001 0.019
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:00.607 0.0%44443 0.000 0.014 0.052
data write00:00:00.991 0.0%38843 0.000 0.026 0.062
idle23:58:11.060100.0%80504 365.227 1071.885 4546.174
status file00:00:00.148 0.0%188 0.000 0.787 3.000
TOTAL: 923:58:13.927100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:54.896100.0%21 335.000 2614.095 12492.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%136 0.000 0.015 0.200
TOTAL: 300:00:54.898100.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 5 11481 4978 6264 239 43%47:47:08
2usenet.blueworldhosting.com 711 7045 741 5321 983 10%23:46:11
3news.tebibyte.org 2 3560 196 3364 0 5%23:58:21
4newsfeed.endofthelinebbs.com 1 5211 106 4985 120 2%25:39:38
5news.sklaffkom.se 22 43 41 2 0 95%05:34:19
6localhost 25 0 0 0 0 0%00:00:01
TOTAL: 6 766 27340 6062 19936 1342 22%126:45:38
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.0 MB372.4 KB371.8 KB15.8 MB 95%3.1 KB
2usenet.blueworldhosting.com2.9 MB117.8 KB1.4 MB4.4 MB 65%2.6 KB
3newsfeed.endofthelinebbs.com2.3 MB0.0 KB221.3 KB2.5 MB 91%11.5 KB
4news.tebibyte.org726.4 KB0.0 KB0.0 KB726.4 KB100%3.7 KB
5news.sklaffkom.se117.8 KB0.0 KB0.0 KB117.8 KB100%2.9 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 621.1 MB490.1 KB2.0 MB23.5 MB 89%3.2 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 6 03:00:03 - 03:59:59 158 2.7% 0.04598.6 KB 2.8% 0.17
Sep 6 04:00:00 - 04:59:59 169 2.8% 0.05522.7 KB 2.5% 0.15
Sep 6 05:00:00 - 05:59:59 138 2.3% 0.04449.2 KB 2.1% 0.12
Sep 6 06:00:00 - 06:59:59 129 2.2% 0.04389.1 KB 1.8% 0.11
Sep 6 07:00:00 - 07:59:59 275 4.6% 0.081.0 MB 5.0% 0.30
Sep 6 08:00:00 - 08:59:59 243 4.1% 0.07827.2 KB 3.9% 0.23
Sep 6 09:00:00 - 09:59:59 209 3.5% 0.061.5 MB 7.5% 0.44
Sep 6 10:00:00 - 10:59:59 269 4.5% 0.071.4 MB 6.6% 0.39
Sep 6 11:00:00 - 11:59:59 274 4.6% 0.08861.8 KB 4.0% 0.24
Sep 6 12:00:00 - 12:59:59 296 5.0% 0.08891.9 KB 4.2% 0.25
Sep 6 13:00:00 - 13:59:59 309 5.2% 0.09875.1 KB 4.1% 0.24
Sep 6 14:00:00 - 14:59:59 292 4.9% 0.08856.1 KB 4.0% 0.24
Sep 6 15:00:00 - 15:59:59 354 6.0% 0.101.4 MB 6.9% 0.41
Sep 6 16:00:00 - 16:59:59 315 5.3% 0.09941.7 KB 4.4% 0.26
Sep 6 17:00:00 - 17:59:59 313 5.3% 0.09877.3 KB 4.1% 0.24
Sep 6 18:00:00 - 18:59:59 316 5.3% 0.091.0 MB 4.9% 0.29
Sep 6 19:00:00 - 19:59:59 343 5.8% 0.101020.1 KB 4.8% 0.28
Sep 6 20:00:00 - 20:59:59 328 5.5% 0.091.0 MB 4.9% 0.29
Sep 6 21:00:00 - 21:59:59 292 4.9% 0.08833.8 KB 3.9% 0.23
Sep 6 22:00:00 - 22:59:59 221 3.7% 0.06602.8 KB 2.8% 0.17
Sep 6 23:00:00 - 23:59:59 184 3.1% 0.051.0 MB 4.9% 0.29
Sep 7 00:00:00 - 00:59:59 209 3.5% 0.06690.2 KB 3.2% 0.19
Sep 7 01:00:00 - 01:59:59 140 2.4% 0.04687.5 KB 3.2% 0.19
Sep 7 02:00:00 - 02:59:59 162 2.7% 0.04686.5 KB 3.2% 0.19
Sep 7 03:00:00 - 03:00:03 9 0.2% 3.008.6 KB 0.0% 2.88
TOTAL: 24:00:00 5947 100.0% 0.0720.8 MB 100.0% 0.25
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 983 332 0 47 0 0 0 0 604
2twilightnode-out.news.weretis.net 304 2 0 140 0 0 0 0 162
3newsfeed.endofthelinebbs.com 115 20 0 0 0 0 0 0 95
4news.tebibyte.org 13 0 0 13 0 0 0 0 0
TOTAL: 4 1415 354 0 200 0 0 0 0 861

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
fido.ru.fidonet.today 24
fido.ru.golded 10
hispagatos.hacking.ransomware 8
fido.golded 6
fido.utf8.ftn.messaging 6
hispagatos.mastodon.tag.hacking 3
S.E.D 1
alt.bbs.files-announce 1
alt.sex.pedophilia 1
chmurka.statystyki 1
fido.ru.mac 1
fidonet.fn_sysop 1
fr.rec.sport.rugby 1
ka.comp.misc 1
tambov.test 1
TOTAL: 16 354

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 597
Too many newsgroups 89
EMP (phn path) 88
Binary: misplaced binary 7
User-issued cancel 7
HTML Multipart 6
Too many newsgroups (meow) 1
TOTAL: 7 795

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 65
newsfeed.endofthelinebbs.com 1
TOTAL: 2 66
TOTAL: 1 66

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7769 2427 3415 67 10 88 31%23:59:57
2sklaffkom.se 6058 1115 2012 2773 19 294 18%23:59:58
3newsfeed.endofthelinebbs.com 10892 18 5767 20 1 289 0%23:59:57
4weretis.net 1035 4 969 0 0 0 0%22:50:04
5usenet.blueworldhosting.com 8465 1 4830 1 0 66 0%23:59:57
TOTAL: 5 34219 3565 16993 2861 30 737 10%118:49:53
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.5 MB611.5 KB7.1 MB0.1 KB/s2.9 KB23:59:57
2sklaffkom.se1.8 MB10.9 MB12.7 MB0.2 KB/s3.4 KB23:59:58
3newsfeed.endofthelinebbs.com78.0 KB61.2 KB139.2 KB0.0 KB/s3.7 KB23:59:57
4weretis.net5.4 KB0.0 KB5.4 KB0.0 KB/s1.4 KB22:50:04
5usenet.blueworldhosting.com1.6 KB7.4 KB9.0 KB0.0 KB/s4.5 KB23:59:57
TOTAL: 58.4 MB11.6 MB19.9 MB0.0 KB/s3.2 KB118:49:53
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
143.98.175.116 3 00.0 KB 0 0 000:00:15
289.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3150.107.36.236 1 00.0 KB 0 0 000:00:24
4194-88-98-88.infrawat.ch 1 00.0 KB 0 0 000:00:00
5205.210.31.35 1 00.0 KB 0 0 000:00:02
6213-166-84-49.infrawat.ch 1 00.0 KB 0 0 000:00:00
781.224.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:04
8o004.scanner.modat.io 1 00.0 KB 0 0 000:00:01
TOTAL: 8 12 00.0 KB 0 0 000:00:54

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 00.0 KB 0 0 000:00:43
2*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:09
3*.infrawat.ch 2 00.0 KB 0 0 000:00:01
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:01
TOTAL: 4 12 00.0 KB 0 0 000:00:54

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
150.107.36.236 0.004 0.008 0.00000:00:24
43.98.175.116 0.008 0.022 0.00000:00:15
81.224.132.66.censys-scanner.com 0.025 0.011 0.00000:00:04
89.195.132.66.censys-scanner.com 0.033 0.022 0.00000:00:04
205.210.31.35 0.003 0.011 0.00000:00:02
o004.scanner.modat.io 0.003 0.010 0.00000:00:01
213-166-84-49.infrawat.ch 0.001 0.010 0.00000:00:00
194-88-98-88.infrawat.ch 0.003 0.013 0.00000:00:00
TOTAL: 8 0.080 0.107 0.00000:00:54

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

SystemConn
43.98.175.116 4
o004.scanner.modat.io 2
81.224.132.66.censys-scanner.com 1
89.195.132.66.censys-scanner.com 1
TOTAL: 4 8

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

CommandCount
EHLO www.censys.io 2
#026#003#001 1
%#037?4? #026b?#0010?)B#022#007<?X?#031?Hvc?)#014s,... 1
?#024 1
?#?' 1
FEAT 1
SYST 1
TOTAL: 7 8

NNRP client timeouts [Top 20]:

SystemConnPeer
213-166-84-49.infrawat.ch 1 1
89.195.132.66.censys-scanner.com 1 2
TOTAL: 2 2 5
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