Daily Usenet report

Sep 15 03:00:03 -- Sep 16 03:00:04

Unknown entries from news log file:

First 50 / 64 lines (78.1%)

Sep 15 03:01:07 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 15 08:15:18 twilightnode nnrpd[3511883]: Reading access from /usr/local/news/etc/readers.conf
Sep 15 08:15:18 twilightnode nnrpd[3511883]: Auth strategy 'localhost' does not match client. Removing.
Sep 15 08:15:18 twilightnode nnrpd[3511883]: 87.251.64.18 res <anonymous>
Sep 15 08:15:18 twilightnode nnrpd[3511883]: 87.251.64.18 no_match_user <anonymous> <localhost>
Sep 15 08:15:18 twilightnode nnrpd[3511883]: 87.251.64.18 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 15 08:15:18 twilightnode nnrpd[3511883]: 87.251.64.18 match_user <anonymous> <anonymous>
Sep 15 08:15:18 twilightnode nnrpd[3511883]: 87.251.64.18 no_post anonymous-read
Sep 15 08:15:18 twilightnode nnrpd[3511884]: Reading access from /usr/local/news/etc/readers.conf
Sep 15 08:15:18 twilightnode nnrpd[3511884]: Auth strategy 'localhost' does not match client. Removing.
Sep 15 08:15:18 twilightnode nnrpd[3511884]: 87.251.64.18 res <anonymous>
Sep 15 08:15:18 twilightnode nnrpd[3511884]: 87.251.64.18 no_match_user <anonymous> <localhost>
Sep 15 08:15:18 twilightnode nnrpd[3511884]: 87.251.64.18 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 15 08:15:18 twilightnode nnrpd[3511884]: 87.251.64.18 match_user <anonymous> <anonymous>
Sep 15 08:15:18 twilightnode nnrpd[3511884]: 87.251.64.18 no_post anonymous-read
Sep 15 14:57:37 twilightnode nnrpd[3529896]: Reading access from /usr/local/news/etc/readers.conf
Sep 15 14:57:37 twilightnode nnrpd[3529896]: Auth strategy 'localhost' does not match client. Removing.
Sep 15 14:57:37 twilightnode nnrpd[3529896]: 194-50-235-144.infrawat.ch res <anonymous>
Sep 15 14:57:37 twilightnode nnrpd[3529896]: 194-50-235-144.infrawat.ch no_match_user <anonymous> <localhost>
Sep 15 14:57:37 twilightnode nnrpd[3529896]: 194-50-235-144.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 15 14:57:37 twilightnode nnrpd[3529896]: 194-50-235-144.infrawat.ch match_user <anonymous> <anonymous>
Sep 15 14:57:37 twilightnode nnrpd[3529896]: 194-50-235-144.infrawat.ch no_post anonymous-read
Sep 15 14:57:44 twilightnode nnrpd[3529898]: Reading access from /usr/local/news/etc/readers.conf
Sep 15 14:57:44 twilightnode nnrpd[3529898]: Auth strategy 'localhost' does not match client. Removing.
Sep 15 14:57:44 twilightnode nnrpd[3529898]: 69-5-169-55.infrawat.ch res <anonymous>
Sep 15 14:57:44 twilightnode nnrpd[3529898]: 69-5-169-55.infrawat.ch no_match_user <anonymous> <localhost>
Sep 15 14:57:44 twilightnode nnrpd[3529898]: 69-5-169-55.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 15 14:57:44 twilightnode nnrpd[3529898]: 69-5-169-55.infrawat.ch match_user <anonymous> <anonymous>
Sep 15 14:57:44 twilightnode nnrpd[3529898]: 69-5-169-55.infrawat.ch no_post anonymous-read
Sep 15 16:01:05 twilightnode nnrpd[3532721]: Reading access from /usr/local/news/etc/readers.conf
Sep 15 16:01:05 twilightnode nnrpd[3532721]: Auth strategy 'localhost' does not match client. Removing.
Sep 15 16:01:05 twilightnode nnrpd[3532721]: 205.210.31.251 res <anonymous>
Sep 15 16:01:05 twilightnode nnrpd[3532721]: 205.210.31.251 no_match_user <anonymous> <localhost>
Sep 15 16:01:05 twilightnode nnrpd[3532721]: 205.210.31.251 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 15 16:01:05 twilightnode nnrpd[3532721]: 205.210.31.251 match_user <anonymous> <anonymous>
Sep 15 16:01:05 twilightnode nnrpd[3532721]: 205.210.31.251 no_post anonymous-read
Sep 15 17:00:03 twilightnode pgpverify[3535441]: pgpverify: verification failed
Sep 15 17:00:05 twilightnode pgpverify[3535459]: pgpverify: verification failed
Sep 15 18:38:16 twilightnode nnrpd[3539952]: Reading access from /usr/local/news/etc/readers.conf
Sep 15 18:38:16 twilightnode nnrpd[3539952]: Auth strategy 'localhost' does not match client. Removing.
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com res <anonymous>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com no_post anonymous-read
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com res <anonymous>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 15 18:38:16 twilightnode nnrpd[3539952]: 102.195.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 22877 79.2%4.5 MB 83.9%
innd 4608 16.0%645.6 KB 11.9%
innfeed 1264 4.4%209.1 KB 3.8%
nnrpd 107 0.4%11.1 KB 0.2%
controlchan 27 0.1%6.2 KB 0.1%
pgpverify 2 0.0%0.4 KB 0.0%
TOTAL: 6 28885 100.0%5.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 53129 43.6%
Negative hits 46137 37.9%
Do not exist 22561 18.5%
Cache misses 37 0.0%
TOTAL: 4 121864 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.016 0.0%18 0.000 0.889 4.000
article cleanup00:00:00.156 0.0%22695 0.000 0.007 0.062
article logging00:00:00.653 0.0%22737 0.000 0.029 0.192
article parse00:00:00.454 0.0%49883 0.000 0.009 0.069
article write00:00:18.209 0.0%21352 0.476 0.853 15.083
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.467 0.0%132332 0.000 0.004 0.033
hisgrep/artcncl00:00:00.008 0.0%18 0.000 0.444 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:13.946 0.0%122047 0.052 0.114 1.772
history sync00:00:00.047 0.0%331 0.000 0.142 1.500
history write00:00:10.202 0.0%22589 0.258 0.452 1.346
idle23:58:57.873 99.9%124523 332.159 693.349 6251.521
nntp read00:00:02.189 0.0%123775 0.000 0.018 0.071
overview write00:00:05.225 0.0%21352 0.056 0.245 2.462
perl filter00:00:28.409 0.0%22589 0.725 1.258 9.962
python filter00:00:01.711 0.0%22589 0.018 0.076 0.923
site send00:00:00.946 0.0%42669 0.000 0.022 0.069
TOTAL: 1824:00:20.511100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.052 0.0%23113 0.000 0.002 0.059
article prepare00:00:00.014 0.0%130484 0.000 0.000 0.012
article read00:00:01.821 0.0%17925 0.000 0.102 0.700
backlog stats00:00:00.116 0.0%224833 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.571 0.0%143820 0.000 0.011 0.024
data write00:00:02.651 0.0%125971 0.007 0.021 0.060
idle23:57:45.774100.0%224833 177.006 383.688 3577.926
status file00:00:00.101 0.0%166 0.000 0.608 3.000
TOTAL: 923:57:52.100100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:12.630100.0%14 20.500 902.143 10485.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%58 0.000 0.017 0.250
TOTAL: 300:00:12.631100.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
group-admin@isc.org002202
TOTAL002202

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 6 42993 12727 30071 195 29%55:57:02
2usenet.blueworldhosting.com 719 22600 7978 13589 1033 35%23:45:20
3newsfeed.endofthelinebbs.com 1 7265 443 6669 153 6%29:23:54
4news.tebibyte.org 1 5916 368 5540 8 6%27:23:33
5news.sklaffkom.se 33 75 60 15 0 80%08:41:51
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 785 78849 21576 55884 1389 27%145:11:40
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net99.0 MB803.9 KB485.2 KB100.2 MB 98%7.9 KB
2usenet.blueworldhosting.com76.3 MB392.4 KB1.5 MB78.2 MB 97%8.9 KB
3newsfeed.endofthelinebbs.com5.3 MB0.0 KB250.2 KB5.5 MB 95%9.5 KB
4news.tebibyte.org1.4 MB6.4 KB6.6 KB1.4 MB 99%3.8 KB
5news.sklaffkom.se190.2 KB0.0 KB0.0 KB190.2 KB100%3.2 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 6182.1 MB1.2 MB2.2 MB185.5 MB 98%8.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 15 03:00:03 - 03:59:59 146 0.7% 0.041.1 MB 0.6% 0.31
Sep 15 04:00:00 - 04:59:59 147 0.7% 0.04636.4 KB 0.3% 0.18
Sep 15 05:00:00 - 05:59:59 191 0.9% 0.051.0 MB 0.6% 0.29
Sep 15 06:00:00 - 06:59:59 115 0.5% 0.03507.2 KB 0.3% 0.14
Sep 15 07:00:00 - 07:59:59 268 1.2% 0.071.5 MB 0.8% 0.44
Sep 15 08:00:00 - 08:59:59 258 1.2% 0.071.1 MB 0.6% 0.32
Sep 15 09:00:00 - 09:59:59 282 1.3% 0.081.3 MB 0.7% 0.37
Sep 15 10:00:00 - 10:59:59 322 1.5% 0.091.8 MB 1.0% 0.51
Sep 15 11:00:00 - 11:59:59 373 1.7% 0.101.2 MB 0.6% 0.33
Sep 15 12:00:00 - 12:59:59 826 3.8% 0.235.8 MB 3.2% 1.65
Sep 15 13:00:00 - 13:59:59 1795 8.4% 0.5016.4 MB 9.0% 4.68
Sep 15 14:00:00 - 14:59:59 1231 5.7% 0.3410.5 MB 5.8% 3.00
Sep 15 15:00:00 - 15:59:59 341 1.6% 0.091.7 MB 1.0% 0.49
Sep 15 16:00:00 - 16:59:59 752 3.5% 0.215.6 MB 3.1% 1.60
Sep 15 17:00:00 - 17:59:59 1551 7.2% 0.4314.0 MB 7.7% 3.98
Sep 15 18:00:00 - 18:59:59 1561 7.3% 0.4314.0 MB 7.7% 3.99
Sep 15 19:00:00 - 19:59:59 1599 7.4% 0.4414.0 MB 7.7% 3.97
Sep 15 20:00:00 - 20:59:59 1568 7.3% 0.4414.1 MB 7.7% 4.01
Sep 15 21:00:00 - 21:59:59 1216 5.7% 0.3410.7 MB 5.9% 3.05
Sep 15 22:00:00 - 22:59:59 1413 6.6% 0.3912.4 MB 6.8% 3.52
Sep 15 23:00:00 - 23:59:59 1471 6.8% 0.4113.7 MB 7.5% 3.90
Sep 16 00:00:00 - 00:59:59 1460 6.8% 0.4113.9 MB 7.6% 3.96
Sep 16 01:00:00 - 01:59:59 1250 5.8% 0.3511.9 MB 6.5% 3.38
Sep 16 02:00:00 - 02:59:59 1344 6.3% 0.3713.2 MB 7.3% 3.76
Sep 16 03:00:00 - 03:00:04 12 0.1% 3.0050.6 KB 0.0% 12.65
TOTAL: 24:00:01 21492 100.0% 0.25182.3 MB 100.0% 2.16
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1034 382 0 46 0 0 0 0 606
2twilightnode-out.news.weretis.net 223 14 0 58 0 0 0 0 151
3newsfeed.endofthelinebbs.com 120 14 0 0 0 0 0 0 106
4news.tebibyte.org 8 0 0 2 0 0 0 0 6
TOTAL: 4 1385 410 0 106 0 0 0 0 869

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 54
osnet.test 15
fido.ru.fidonet.today 11
hispagatos.mastodon.tag.hacking 9
pl.listserv.chomor-l 7
fido.golded 4
alt.sports.football.pro.dallas-cowboys 2
alt.test.9 2
cn.sci.physics 2
de.comm.software.mailreader 2
fido.ru.modem 2
fido.ru.space 2
S.E.D 1
alt.agriculture.beef 1
alt.bbs.files-announce 1
amiga.test 1
chmurka.statystyki 1
de.comp.lang.python 1
fido.ru.home 1
TOTAL: 23 410

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 629
Too many newsgroups 100
EMP (phn path) 51
User-issued cancel 40
HTML Multipart 7
TOTAL: 5 827

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 41
usenet.blueworldhosting.com 1
TOTAL: 2 42
TOTAL: 1 42

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 24902 14697 6638 117 0 128 59%23:59:51
2sklaffkom.se 21789 857 3634 16965 0 61 3%23:59:34
3newsfeed.endofthelinebbs.com 28591 28 20997 23 0 1484 0%23:59:48
4weretis.net 8960 10 8824 0 0 0 0%23:59:57
5usenet.blueworldhosting.com 12830 0 9398 0 0 36 0%23:59:57
TOTAL: 5 97072 15592 49491 17105 0 1709 16%119:59:07
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org136.8 MB709.2 KB137.5 MB1.6 KB/s9.5 KB23:59:51
2sklaffkom.se1.4 MB165.4 MB166.7 MB2.0 KB/s9.6 KB23:59:34
3newsfeed.endofthelinebbs.com78.6 KB147.0 KB225.7 KB0.0 KB/s4.4 KB23:59:48
4weretis.net18.4 KB0.0 KB18.4 KB0.0 KB/s1.8 KB23:59:57
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
TOTAL: 5138.3 MB166.2 MB304.5 MB0.7 KB/s9.5 KB119:59:07
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
187.251.64.18 2 00.0 KB 0 0 000:00:00
2azpdws69fxg1.stretchoid.com 2 00.0 KB 0 0 000:00:10
3102.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4194-50-235-144.infrawat.ch 1 00.0 KB 0 0 000:00:00
5205.210.31.251 1 00.0 KB 0 0 000:00:00
669-5-169-55.infrawat.ch 1 00.0 KB 0 0 000:00:00
TOTAL: 6 8 00.0 KB 0 0 000:00:12

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 0 0 000:00:00
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 8 00.0 KB 0 0 000:00:12

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdws69fxg1.stretchoid.com 0.003 0.024 0.00000:00:10
102.195.132.66.censys-scanner.com 0.031 0.014 0.00000:00:00
205.210.31.251 0.003 0.012 0.00000:00:00
69-5-169-55.infrawat.ch 0.008 0.004 0.00000:00:00
87.251.64.18 0.009 0.015 0.00000:00:00
194-50-235-144.infrawat.ch 0.003 0.010 0.00000:00:00
TOTAL: 6 0.057 0.079 0.00000:00:12

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

SystemConn
87.251.64.18 10
102.195.132.66.censys-scanner.com 1
azpdws69fxg1.stretchoid.com 1
TOTAL: 3 12

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

CommandCount
#026#003#001#002 1
?#024?#011?#023 1
Accept-Encoding: gzip, deflate, br 1
Accept-Language: en-US,en;q=0.9 1
Accept: text/html,application/xhtml+xml,... 1
Connection: close 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
y?#017? ?]?c?R>?g?D?P#037?=C?80qO 1
TOTAL: 12 12

NNRP client timeouts [Top 20]:

SystemConnPeer
69-5-169-55.infrawat.ch 1 1
TOTAL: 1 1 3
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