Daily Usenet report

Sep 1 03:00:04 -- Sep 2 03:00:04

Unknown entries from news log file:

First 50 / 78 lines (64.1%)

Sep 1 03:00:59 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 1 04:02:24 twilightnode nnrpd[2539024]: Reading access from /usr/local/news/etc/readers.conf
Sep 1 04:02:24 twilightnode nnrpd[2539024]: Auth strategy 'localhost' does not match client. Removing.
Sep 1 04:02:24 twilightnode nnrpd[2539024]: zl-dala-us-gp1-wk121c.internet-census.org res <anonymous>
Sep 1 04:02:24 twilightnode nnrpd[2539024]: zl-dala-us-gp1-wk121c.internet-census.org no_match_user <anonymous> <localhost>
Sep 1 04:02:24 twilightnode nnrpd[2539024]: zl-dala-us-gp1-wk121c.internet-census.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 1 04:02:24 twilightnode nnrpd[2539024]: zl-dala-us-gp1-wk121c.internet-census.org match_user <anonymous> <anonymous>
Sep 1 04:02:24 twilightnode nnrpd[2539024]: zl-dala-us-gp1-wk121c.internet-census.org no_post anonymous-read
Sep 1 06:47:10 twilightnode nnrpd[2546694]: Reading access from /usr/local/news/etc/readers.conf
Sep 1 06:47:10 twilightnode nnrpd[2546694]: Auth strategy 'localhost' does not match client. Removing.
Sep 1 06:47:10 twilightnode nnrpd[2546694]: 45.205.1.245 res <anonymous>
Sep 1 06:47:10 twilightnode nnrpd[2546694]: 45.205.1.245 no_match_user <anonymous> <localhost>
Sep 1 06:47:10 twilightnode nnrpd[2546694]: 45.205.1.245 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 1 06:47:10 twilightnode nnrpd[2546694]: 45.205.1.245 match_user <anonymous> <anonymous>
Sep 1 06:47:10 twilightnode nnrpd[2546694]: 45.205.1.245 no_post anonymous-read
Sep 1 09:15:02 twilightnode pgpverify[2554869]: pgpverify: verification failed
Sep 1 10:58:41 twilightnode nnrpd[2559661]: Reading access from /usr/local/news/etc/readers.conf
Sep 1 10:58:41 twilightnode nnrpd[2559661]: Auth strategy 'localhost' does not match client. Removing.
Sep 1 10:58:41 twilightnode nnrpd[2559661]: 151.243.11.236 res <anonymous>
Sep 1 10:58:41 twilightnode nnrpd[2559661]: 151.243.11.236 no_match_user <anonymous> <localhost>
Sep 1 10:58:41 twilightnode nnrpd[2559661]: 151.243.11.236 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 1 10:58:41 twilightnode nnrpd[2559661]: 151.243.11.236 match_user <anonymous> <anonymous>
Sep 1 10:58:41 twilightnode nnrpd[2559661]: 151.243.11.236 no_post anonymous-read
Sep 1 11:46:45 twilightnode nnrpd[2561777]: Reading access from /usr/local/news/etc/readers.conf
Sep 1 11:46:45 twilightnode nnrpd[2561777]: Auth strategy 'localhost' does not match client. Removing.
Sep 1 11:46:45 twilightnode nnrpd[2561777]: 5-252-83-4.infrawat.ch res <anonymous>
Sep 1 11:46:45 twilightnode nnrpd[2561777]: 5-252-83-4.infrawat.ch no_match_user <anonymous> <localhost>
Sep 1 11:46:45 twilightnode nnrpd[2561777]: 5-252-83-4.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 1 11:46:45 twilightnode nnrpd[2561777]: 5-252-83-4.infrawat.ch match_user <anonymous> <anonymous>
Sep 1 11:46:45 twilightnode nnrpd[2561777]: 5-252-83-4.infrawat.ch no_post anonymous-read
Sep 1 11:47:13 twilightnode nnrpd[2561781]: Reading access from /usr/local/news/etc/readers.conf
Sep 1 11:47:13 twilightnode nnrpd[2561781]: Auth strategy 'localhost' does not match client. Removing.
Sep 1 11:47:13 twilightnode nnrpd[2561781]: 188-240-59-61.infrawat.ch res <anonymous>
Sep 1 11:47:13 twilightnode nnrpd[2561781]: 188-240-59-61.infrawat.ch no_match_user <anonymous> <localhost>
Sep 1 11:47:13 twilightnode nnrpd[2561781]: 188-240-59-61.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 1 11:47:13 twilightnode nnrpd[2561781]: 188-240-59-61.infrawat.ch match_user <anonymous> <anonymous>
Sep 1 11:47:13 twilightnode nnrpd[2561781]: 188-240-59-61.infrawat.ch no_post anonymous-read
Sep 1 13:30:45 twilightnode nnrpd[2566183]: Reading access from /usr/local/news/etc/readers.conf
Sep 1 13:30:45 twilightnode nnrpd[2566183]: Auth strategy 'localhost' does not match client. Removing.
Sep 1 13:30:45 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com res <anonymous>
Sep 1 13:30:45 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 1 13:30:45 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 1 13:30:45 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 1 13:30:45 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com no_post anonymous-read
Sep 1 13:30:47 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com res <anonymous>
Sep 1 13:30:47 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 1 13:30:47 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 1 13:30:47 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 1 13:30:47 twilightnode nnrpd[2566183]: 159.186.132.66.censys-scanner.com no_post anonymous-read
Sep 1 13:59:21 twilightnode nnrpd[2567394]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
inn 7604 55.6%1.3 MB 60.9%
innd 4640 33.9%658.4 KB 29.7%
innfeed 1256 9.2%183.4 KB 8.3%
nnrpd 117 0.9%12.3 KB 0.6%
controlchan 51 0.4%12.2 KB 0.6%
pgpverify 2 0.0%0.2 KB 0.0%
TOTAL: 6 13670 100.0%2.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20645 52.3%
Negative hits 11313 28.6%
Do not exist 7538 19.1%
Cache misses 8 0.0%
TOTAL: 4 39504 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.036 0.0%20 0.000 1.800 18.000
article cleanup00:00:00.068 0.0%7565 0.000 0.009 0.067
article logging00:00:00.225 0.0%7604 0.000 0.030 0.379
article parse00:00:00.112 0.0%10552 0.000 0.011 0.060
article write00:00:06.637 0.0%6289 0.557 1.055 2.481
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.238 0.0%42433 0.000 0.006 0.037
hisgrep/artcncl00:00:00.010 0.0%20 0.000 0.500 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.162 0.0%39646 0.063 0.105 0.248
history sync00:00:00.056 0.0%419 0.000 0.134 1.333
history write00:00:03.153 0.0%7565 0.173 0.417 0.878
idle24:00:32.482 99.9%43188 1068.820 2001.308 6594.121
nntp read00:00:00.823 0.0%42131 0.000 0.020 0.057
overview write00:00:04.434 0.0%6289 0.213 0.705 1.556
perl filter00:00:11.770 0.0%7565 0.792 1.556 7.067
python filter00:00:00.713 0.0%7565 0.000 0.094 0.400
site send00:00:00.253 0.0%12549 0.000 0.020 0.083
TOTAL: 1824:01:05.172100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%6645 0.000 0.004 0.053
article prepare00:00:00.007 0.0%27800 0.000 0.000 0.009
article read00:00:00.934 0.0%4383 0.000 0.213 1.364
backlog stats00:00:00.074 0.0%83967 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.596 0.0%47101 0.000 0.013 0.055
data write00:00:00.997 0.0%40903 0.000 0.024 0.056
idle23:51:01.792100.0%83967 358.812 1022.566 3234.551
status file00:00:00.163 0.0%162 0.000 1.006 8.000
TOTAL: 923:51:04.591100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:40.198100.0%13 82.000 3092.154 10379.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%69 0.000 0.029 0.500
TOTAL: 300:00:40.200100.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
bofh-control@killfile.org001001
control@eternal-september.org001001
moderator@dana.de001101
us-control@lists.killfile.org001101
TOTAL004204

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 6 13569 4995 8457 117 36%56:20:46
2usenet.blueworldhosting.com 719 7492 1017 5434 1041 13%23:46:03
3newsfeed.endofthelinebbs.com 1 5140 323 4722 95 6%22:43:09
4news.sklaffkom.se 29 44 33 10 1 75%06:05:50
5news.tebibyte.org 2 3426 23 3403 0 0%23:58:25
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 782 29671 6391 22026 1254 21%132:54:13
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net16.3 MB0.0 KB159.8 KB16.5 MB 99%3.3 KB
2usenet.blueworldhosting.com5.7 MB0.0 KB1.5 MB7.2 MB 79%3.6 KB
3newsfeed.endofthelinebbs.com4.2 MB0.0 KB149.0 KB4.4 MB 96%10.7 KB
4news.tebibyte.org96.1 KB0.0 KB0.0 KB96.1 KB100%4.2 KB
5news.sklaffkom.se76.8 KB0.0 KB1.0 KB77.7 KB 98%2.3 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 626.4 MB0.0 KB1.8 MB28.2 MB 93%3.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 1 03:00:04 - 03:59:59 174 2.8% 0.05750.6 KB 2.8% 0.21
Sep 1 04:00:00 - 04:59:59 140 2.2% 0.04624.6 KB 2.4% 0.17
Sep 1 05:00:00 - 05:59:59 120 1.9% 0.03561.9 KB 2.1% 0.16
Sep 1 06:00:00 - 06:59:59 170 2.7% 0.05525.8 KB 2.0% 0.15
Sep 1 07:00:00 - 07:59:59 179 2.8% 0.05828.3 KB 3.1% 0.23
Sep 1 08:00:00 - 08:59:59 210 3.3% 0.061.2 MB 4.8% 0.35
Sep 1 09:00:00 - 09:59:59 310 4.9% 0.091.2 MB 4.6% 0.34
Sep 1 10:00:00 - 10:59:59 302 4.8% 0.081.5 MB 5.9% 0.43
Sep 1 11:00:00 - 11:59:59 358 5.7% 0.101.1 MB 4.2% 0.31
Sep 1 12:00:00 - 12:59:59 278 4.4% 0.081.0 MB 3.9% 0.29
Sep 1 13:00:00 - 13:59:59 300 4.8% 0.081.4 MB 5.3% 0.39
Sep 1 14:00:00 - 14:59:59 306 4.9% 0.09981.5 KB 3.7% 0.27
Sep 1 15:00:00 - 15:59:59 306 4.9% 0.091.2 MB 4.6% 0.34
Sep 1 16:00:00 - 16:59:59 352 5.6% 0.101.2 MB 4.6% 0.34
Sep 1 17:00:00 - 17:59:59 405 6.4% 0.111.7 MB 6.5% 0.48
Sep 1 18:00:00 - 18:59:59 347 5.5% 0.101.3 MB 4.9% 0.36
Sep 1 19:00:00 - 19:59:59 327 5.2% 0.091.8 MB 6.8% 0.50
Sep 1 20:00:00 - 20:59:59 348 5.5% 0.101.3 MB 5.1% 0.37
Sep 1 21:00:00 - 21:59:59 281 4.5% 0.081.3 MB 5.0% 0.37
Sep 1 22:00:00 - 22:59:59 265 4.2% 0.071003.1 KB 3.8% 0.28
Sep 1 23:00:00 - 23:59:59 295 4.7% 0.081.3 MB 5.1% 0.37
Sep 2 00:00:00 - 00:59:59 220 3.5% 0.061.0 MB 3.9% 0.29
Sep 2 01:00:00 - 01:59:59 148 2.4% 0.04670.7 KB 2.5% 0.19
Sep 2 02:00:00 - 02:59:59 137 2.2% 0.04577.0 KB 2.2% 0.16
Sep 2 03:00:00 - 03:00:04 10 0.2% 2.5022.8 KB 0.1% 5.70
TOTAL: 24:00:00 6288 100.0% 0.0725.8 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1041 374 0 0 0 0 0 0 667
2twilightnode-out.news.weretis.net 154 17 0 0 0 0 0 0 137
3newsfeed.endofthelinebbs.com 118 8 0 0 0 0 0 0 110
4news.tebibyte.org 2 0 0 0 0 0 0 0 2
5news.sklaffkom.se 1 0 0 0 0 0 0 0 1
TOTAL: 5 1316 399 0 0 0 0 0 0 917

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 33
fido.ru.fido.internet 11
hispagatos.mastodon.tag.hacking 7
nyc.forsale 6
free.fr.test 4
maine.forsale 4
chi.forsale 3
dfw.forsale 3
fido.ru.golded 3
local.general 3
alt.sex.pedophilia 2
fido.su.pol 2
harvard.forsale 2
kraft.politics 2
S.E.D 1
alt.2600.madrid 1
alt.aviation 1
alt.bbs.files-announce 1
alt.forsale.seattle 1
TOTAL: 41 399

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 731
User-issued cancel 92
Too many newsgroups 51
Binary: misplaced binary 2
HTML Multipart 2
TOTAL: 5 878

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8041 2526 3552 136 38 20 31%23:59:56
2sklaffkom.se 6377 990 1957 3259 48 1 15%23:59:13
3newsfeed.endofthelinebbs.com 11241 13 5934 3 0 376 0%23:59:20
4usenet.blueworldhosting.com 8281 5 4844 0 0 23 0%23:59:22
5weretis.net 1484 3 1429 0 0 0 0%23:59:03
TOTAL: 5 35424 3537 17716 3398 86 420 9%119:56:54
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.9 MB847.9 KB7.8 MB0.1 KB/s3.0 KB23:59:56
2sklaffkom.se1.5 MB16.0 MB17.6 MB0.2 KB/s4.2 KB23:59:13
3newsfeed.endofthelinebbs.com44.1 KB9.9 KB54.0 KB0.0 KB/s3.4 KB23:59:20
4usenet.blueworldhosting.com17.9 KB0.0 KB17.9 KB0.0 KB/s3.6 KB23:59:22
5weretis.net5.4 KB0.0 KB5.4 KB0.0 KB/s1.8 KB23:59:03
TOTAL: 58.5 MB16.9 MB25.4 MB0.1 KB/s3.8 KB119:56:54
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1147.185.132.189 1 00.0 KB 0 0 000:00:00
2151.243.11.236 1 00.0 KB 0 0 000:00:10
3159.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
4172.233.26.186.socradar-curiosity-wide-scan-project.socradar.com 1 00.0 KB 0 0 000:00:10
5188-240-59-61.infrawat.ch 1 00.0 KB 0 0 000:00:00
645.205.1.245 1 00.0 KB 0 0 000:00:03
75-252-83-4.infrawat.ch 1 00.0 KB 0 0 000:00:00
8o051.scanner.modat.io 1 00.0 KB 0 0 000:00:00
9o060.scanner.modat.io 1 00.0 KB 0 0 000:00:00
10zl-dala-us-gp1-wk121c.internet-census.org 1 00.0 KB 0 0 000:00:11
TOTAL: 10 10 00.0 KB 0 0 000:00:40

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 0 0 000:00:14
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
5*.internet-census.org 1 00.0 KB 0 0 000:00:11
6*.socradar-curiosity-wide-scan-project.socradar.com 1 00.0 KB 0 0 000:00:10
TOTAL: 6 10 00.0 KB 0 0 000:00:40

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
zl-dala-us-gp1-wk121c.internet-census.org 0.006 0.016 0.00000:00:11
151.243.11.236 0.003 0.013 0.00000:00:10
172.233.26.186.socradar-curiosity-wide-scan-project.socradar.com 0.004 0.017 0.00000:00:10
45.205.1.245 0.002 0.011 0.00000:00:03
159.186.132.66.censys-scanner.com 0.027 0.014 0.00000:00:03
5-252-83-4.infrawat.ch 0.006 0.024 0.00000:00:00
o060.scanner.modat.io 0.003 0.012 0.00000:00:00
147.185.132.189 0.005 0.017 0.00000:00:00
o051.scanner.modat.io 0.006 0.014 0.00000:00:00
188-240-59-61.infrawat.ch 0.004 0.005 0.00000:00:00
TOTAL: 10 0.066 0.143 0.00000:00:40

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

SystemConn
159.186.132.66.censys-scanner.com 1
TOTAL: 1 1

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

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
188-240-59-61.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