Daily Usenet report

Oct 27 03:00:04 -- Oct 28 03:00:05

Unknown entries from news log file:

First 50 / 83 lines (60.2%)

Oct 27 03:01:12 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Oct 27 06:59:50 twilightnode nnrpd[500440]: Reading access from /usr/local/news/etc/readers.conf
Oct 27 06:59:50 twilightnode nnrpd[500440]: Auth strategy 'localhost' does not match client. Removing.
Oct 27 06:59:50 twilightnode nnrpd[500440]: azpdesu6xydc.stretchoid.com res <anonymous>
Oct 27 06:59:50 twilightnode nnrpd[500440]: azpdesu6xydc.stretchoid.com no_match_user <anonymous> <localhost>
Oct 27 06:59:50 twilightnode nnrpd[500440]: azpdesu6xydc.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 27 06:59:50 twilightnode nnrpd[500440]: azpdesu6xydc.stretchoid.com match_user <anonymous> <anonymous>
Oct 27 06:59:50 twilightnode nnrpd[500440]: azpdesu6xydc.stretchoid.com no_post anonymous-read
Oct 27 09:50:40 twilightnode nnrpd[508496]: Reading access from /usr/local/news/etc/readers.conf
Oct 27 09:50:40 twilightnode nnrpd[508496]: Auth strategy 'localhost' does not match client. Removing.
Oct 27 09:50:40 twilightnode nnrpd[508496]: 81-19-216-71.infrawat.ch res <anonymous>
Oct 27 09:50:40 twilightnode nnrpd[508496]: 81-19-216-71.infrawat.ch no_match_user <anonymous> <localhost>
Oct 27 09:50:40 twilightnode nnrpd[508496]: 81-19-216-71.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 27 09:50:40 twilightnode nnrpd[508496]: 81-19-216-71.infrawat.ch match_user <anonymous> <anonymous>
Oct 27 09:50:40 twilightnode nnrpd[508496]: 81-19-216-71.infrawat.ch no_post anonymous-read
Oct 27 09:51:03 twilightnode nnrpd[508502]: Reading access from /usr/local/news/etc/readers.conf
Oct 27 09:51:03 twilightnode nnrpd[508502]: Auth strategy 'localhost' does not match client. Removing.
Oct 27 09:51:03 twilightnode nnrpd[508502]: 5-226-140-73.infrawat.ch res <anonymous>
Oct 27 09:51:03 twilightnode nnrpd[508502]: 5-226-140-73.infrawat.ch no_match_user <anonymous> <localhost>
Oct 27 09:51:03 twilightnode nnrpd[508502]: 5-226-140-73.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 27 09:51:03 twilightnode nnrpd[508502]: 5-226-140-73.infrawat.ch match_user <anonymous> <anonymous>
Oct 27 09:51:03 twilightnode nnrpd[508502]: 5-226-140-73.infrawat.ch no_post anonymous-read
Oct 27 11:33:46 twilightnode nnrpd[513630]: Reading access from /usr/local/news/etc/readers.conf
Oct 27 11:33:46 twilightnode nnrpd[513630]: Auth strategy 'localhost' does not match client. Removing.
Oct 27 11:33:46 twilightnode nnrpd[513630]: 198.235.24.127 res <anonymous>
Oct 27 11:33:46 twilightnode nnrpd[513630]: 198.235.24.127 no_match_user <anonymous> <localhost>
Oct 27 11:33:46 twilightnode nnrpd[513630]: 198.235.24.127 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 27 11:33:46 twilightnode nnrpd[513630]: 198.235.24.127 match_user <anonymous> <anonymous>
Oct 27 11:33:46 twilightnode nnrpd[513630]: 198.235.24.127 no_post anonymous-read
Oct 27 15:39:30 twilightnode nnrpd[525269]: Reading access from /usr/local/news/etc/readers.conf
Oct 27 15:39:30 twilightnode nnrpd[525269]: Auth strategy 'localhost' does not match client. Removing.
Oct 27 15:39:30 twilightnode nnrpd[525269]: 97.79.29.163 res <anonymous>
Oct 27 15:39:30 twilightnode nnrpd[525269]: 97.79.29.163 no_match_user <anonymous> <localhost>
Oct 27 15:39:30 twilightnode nnrpd[525269]: 97.79.29.163 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 27 15:39:30 twilightnode nnrpd[525269]: 97.79.29.163 match_user <anonymous> <anonymous>
Oct 27 15:39:30 twilightnode nnrpd[525269]: 97.79.29.163 no_post anonymous-read
Oct 27 17:19:05 twilightnode nnrpd[529969]: Reading access from /usr/local/news/etc/readers.conf
Oct 27 17:19:05 twilightnode nnrpd[529969]: Auth strategy 'localhost' does not match client. Removing.
Oct 27 17:19:05 twilightnode nnrpd[529969]: 107.172.132.66.censys-scanner.com res <anonymous>
Oct 27 17:19:05 twilightnode nnrpd[529969]: 107.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Oct 27 17:19:05 twilightnode nnrpd[529969]: 107.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 27 17:19:05 twilightnode nnrpd[529969]: 107.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Oct 27 17:19:05 twilightnode nnrpd[529969]: 107.172.132.66.censys-scanner.com no_post anonymous-read
Oct 27 17:19:12 twilightnode nnrpd[529972]: Reading access from /usr/local/news/etc/readers.conf
Oct 27 17:19:12 twilightnode nnrpd[529972]: Auth strategy 'localhost' does not match client. Removing.
Oct 27 17:19:12 twilightnode nnrpd[529972]: 107.172.132.66.censys-scanner.com res <anonymous>
Oct 27 17:19:12 twilightnode nnrpd[529972]: 107.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Oct 27 17:19:12 twilightnode nnrpd[529972]: 107.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Oct 27 17:19:12 twilightnode nnrpd[529972]: 107.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Oct 27 17:19:12 twilightnode nnrpd[529972]: 107.172.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 7524 45.7%1.3 MB 51.5%
innd 7524 45.7%1.0 MB 40.4%
innfeed 1280 7.8%192.2 KB 7.5%
nnrpd 137 0.8%14.0 KB 0.5%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 16466 100.0%2.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19791 52.3%
Negative hits 10584 28.0%
Do not exist 7463 19.7%
Cache misses 1 0.0%
TOTAL: 4 37839 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.072 0.0%23 1.000 3.130 5.000
article cleanup00:00:00.064 0.0%7505 0.000 0.009 0.071
article logging00:00:00.269 0.0%7539 0.000 0.036 0.182
article parse00:00:00.167 0.0%10560 0.000 0.016 0.143
article write00:00:07.657 0.0%6007 0.605 1.275 2.917
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.302 0.0%42346 0.000 0.007 0.084
hisgrep/artcncl00:00:00.048 0.0%23 0.000 2.087 3.300
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.533 0.0%38005 0.073 0.146 0.319
history sync00:00:00.062 0.0%399 0.000 0.155 1.000
history write00:00:03.753 0.0%7505 0.250 0.500 0.889
idle24:00:07.335 99.9%44215 1031.947 1954.254 7897.039
nntp read00:00:01.078 0.0%42506 0.000 0.025 0.157
overview write00:00:05.952 0.0%6007 0.323 0.991 3.727
perl filter00:00:11.701 0.0%7505 0.896 1.559 6.071
python filter00:00:00.734 0.0%7505 0.000 0.098 0.600
site send00:00:00.387 0.0%11967 0.000 0.032 0.429
TOTAL: 1824:00:45.114100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%6121 0.000 0.005 0.125
article prepare00:00:00.001 0.0%26232 0.000 0.000 0.028
article read00:00:01.272 0.0%4253 0.000 0.299 1.250
backlog stats00:00:00.047 0.0%81532 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.690 0.0%45464 0.000 0.015 0.104
data write00:00:01.236 0.0%39397 0.000 0.031 0.075
idle23:59:50.268100.0%81532 360.295 1059.587 4143.848
status file00:00:00.284 0.0%175 0.000 1.623 5.000
TOTAL: 923:59:53.826100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:25.844100.0%21 146.000 1230.667 10037.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%107 0.000 0.009 0.021
readart00:00:00.000 0.0%1 0.000 0.000 0.000
TOTAL: 400:00:25.845100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 12443 4679 7611 153 37%52:44:42
2usenet.blueworldhosting.com 1373 7134 838 5102 1194 11%23:56:35
3newsfeed.endofthelinebbs.com 1 13445 323 12964 158 2%20:47:57
4news.sklaffkom.se 42 128 119 9 0 92%12:48:22
5news.tebibyte.org 2 4085 50 4034 1 1%23:58:23
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 1448 37235 6009 29720 1506 16%134:15:59
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.3 MB0.0 KB711.2 KB16.0 MB 95%3.4 KB
2newsfeed.endofthelinebbs.com5.3 MB0.0 KB227.1 KB5.6 MB 96%11.8 KB
3usenet.blueworldhosting.com4.5 MB0.0 KB2.3 MB6.8 MB 65%3.4 KB
4news.sklaffkom.se347.3 KB0.0 KB0.0 KB347.3 KB100%2.9 KB
5news.tebibyte.org200.8 KB0.0 KB1.3 KB202.1 KB 99%4.0 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 625.7 MB0.0 KB3.3 MB28.9 MB 88%3.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 27 03:00:04 - 03:59:59 124 2.1% 0.03602.6 KB 2.4% 0.17
Oct 27 04:00:00 - 04:59:59 99 1.7% 0.03459.2 KB 1.8% 0.13
Oct 27 05:00:00 - 05:59:59 80 1.3% 0.02255.8 KB 1.0% 0.07
Oct 27 06:00:00 - 06:59:59 131 2.2% 0.04519.8 KB 2.1% 0.14
Oct 27 07:00:00 - 07:59:59 161 2.7% 0.04609.9 KB 2.4% 0.17
Oct 27 08:00:00 - 08:59:59 213 3.6% 0.06758.0 KB 3.0% 0.21
Oct 27 09:00:00 - 09:59:59 269 4.5% 0.071.2 MB 4.7% 0.33
Oct 27 10:00:00 - 10:59:59 280 4.7% 0.081.1 MB 4.5% 0.31
Oct 27 11:00:00 - 11:59:59 282 4.7% 0.081.3 MB 5.5% 0.38
Oct 27 12:00:00 - 12:59:59 283 4.7% 0.081021.5 KB 4.1% 0.28
Oct 27 13:00:00 - 13:59:59 358 6.0% 0.101.3 MB 5.5% 0.38
Oct 27 14:00:00 - 14:59:59 348 5.8% 0.101.3 MB 5.3% 0.37
Oct 27 15:00:00 - 15:59:59 368 6.1% 0.101.4 MB 5.6% 0.39
Oct 27 16:00:00 - 16:59:59 279 4.7% 0.081.5 MB 6.2% 0.43
Oct 27 17:00:00 - 17:59:59 330 5.5% 0.091.3 MB 5.4% 0.37
Oct 27 18:00:00 - 18:59:59 408 6.8% 0.111.3 MB 5.3% 0.37
Oct 27 19:00:00 - 19:59:59 352 5.9% 0.101.3 MB 5.3% 0.37
Oct 27 20:00:00 - 20:59:59 289 4.8% 0.081.1 MB 4.3% 0.30
Oct 27 21:00:00 - 21:59:59 304 5.1% 0.081.4 MB 5.7% 0.39
Oct 27 22:00:00 - 22:59:59 261 4.4% 0.071.1 MB 4.5% 0.31
Oct 27 23:00:00 - 23:59:59 286 4.8% 0.081.4 MB 5.6% 0.39
Oct 28 00:00:00 - 00:59:59 219 3.7% 0.061.1 MB 4.4% 0.31
Oct 28 01:00:00 - 01:59:59 143 2.4% 0.04755.8 KB 3.0% 0.21
Oct 28 02:00:00 - 03:00:05 127 2.1% 0.04528.4 KB 2.1% 0.15
TOTAL: 24:00:01 5994 100.0% 0.0724.4 MB 100.0% 0.29
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1194 332 0 0 0 0 0 0 862
2newsfeed.endofthelinebbs.com 174 4 0 0 0 0 0 0 170
3twilightnode-out.news.weretis.net 162 20 0 0 0 0 0 0 142
TOTAL: 3 1530 356 0 0 0 0 0 0 1174

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 28
hispagatos.mastodon.tag.hacking 5
alt.politics.communism 3
fido.ru.home 3
oc.forsale 3
alt.pl.internet 2
tw.bbs.talk 2
alt.2600.madrid 1
alt.ads 1
alt.autos.performance-cars 1
alt.bbs.files-announce 1
alt.christnet.atheism 1
alt.genealogy 1
alt.music 1
alt.personal.ads 1
alt.personals.ads 1
alt.politics.bush 1
alt.religion.christian.calvary-chapel 1
alt.religion.islam.arabic 1
TOTAL: 30 356

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 931
Too many newsgroups 107
EMP (phn path) 61
User-issued cancel 35
HTML Multipart 4
Too many newsgroups (meow) 2
TOTAL: 6 1140

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7720 2281 3580 87 0 3 29%23:59:46
2sklaffkom.se 6114 955 1855 3137 0 0 15%23:59:56
3newsfeed.endofthelinebbs.com 10456 64 5501 24 0 167 0%23:59:55
4weretis.net 1457 7 1369 0 0 0 0%23:33:15
5usenet.blueworldhosting.com 7978 3 4725 0 0 1 0%23:59:46
TOTAL: 5 33725 3310 17030 3248 0 171 9%119:32:38
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.2 MB1.1 MB7.2 MB0.1 KB/s3.1 KB23:59:46
2sklaffkom.se1.6 MB15.8 MB17.4 MB0.2 KB/s4.3 KB23:59:56
3newsfeed.endofthelinebbs.com163.7 KB77.5 KB241.2 KB0.0 KB/s2.7 KB23:59:55
4weretis.net11.6 KB0.0 KB11.6 KB0.0 KB/s1.7 KB23:33:15
5usenet.blueworldhosting.com8.6 KB0.0 KB8.6 KB0.0 KB/s2.9 KB23:59:46
TOTAL: 57.9 MB16.9 MB24.8 MB0.1 KB/s3.9 KB119:32:38
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
197.79.29.163 1 11.0 KB 1 0 000:00:01
TOTAL: 1 1 11.0 KB 1 0 000:00:01

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 5 11.0 KB 1 0 000:00:13
2*.66.censys-scanner.com 3 00.0 KB 0 0 000:00:02
3*.infrawat.ch 2 00.0 KB 0 0 000:00:00
4*.stretchoid.com 1 00.0 KB 0 0 000:00:09
TOTAL: 4 11 11.0 KB 1 0 000:00:25

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
118.194.251.141 0.012 0.024 0.00000:00:11
azpdesu6xydc.stretchoid.com 0.010 0.014 0.00000:00:09
107.172.132.66.censys-scanner.com 0.039 0.036 0.00000:00:02
97.79.29.163 0.006 0.014 0.00000:00:01
198.235.24.127 0.005 0.013 0.00000:00:00
5-226-140-73.infrawat.ch 0.006 0.006 0.00000:00:00
81-19-216-71.infrawat.ch 0.005 0.016 0.00000:00:00
TOTAL: 7 0.083 0.123 0.00000:00:25

Curious NNRP explorers [Top 20]:

SystemConn
107.172.132.66.censys-scanner.com 3
118.194.251.141 3
198.235.24.127 1
5-226-140-73.infrawat.ch 1
81-19-216-71.infrawat.ch 1
azpdesu6xydc.stretchoid.com 1
TOTAL: 6 10

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

SystemConn
118.194.251.141 2
107.172.132.66.censys-scanner.com 1
TOTAL: 2 3

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

CommandCount
#026#003#001 1
?#024 1
EHLO www.censys.io 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
107.172.132.66.censys-scanner.com 1 2
5-226-140-73.infrawat.ch 1 1
TOTAL: 2 2 5

Newsgroup request counts (by hierarchy) [Top 100]:

HierarchyCountPct
1comp 1100.0%
TOTAL: 1 1100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1comp.lang.awk 1
TOTAL: 1 1
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