Daily Usenet report

Aug 31 03:00:04 -- Sep 1 03:00:03

Unknown entries from news log file:

First 50 / 83 lines (60.2%)

Aug 31 03:00:59 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 31 06:51:54 twilightnode nnrpd[2480545]: Reading access from /usr/local/news/etc/readers.conf
Aug 31 06:51:54 twilightnode nnrpd[2480545]: Auth strategy 'localhost' does not match client. Removing.
Aug 31 06:51:54 twilightnode nnrpd[2480545]: 69-5-169-211.infrawat.ch res <anonymous>
Aug 31 06:51:54 twilightnode nnrpd[2480545]: 69-5-169-211.infrawat.ch no_match_user <anonymous> <localhost>
Aug 31 06:51:54 twilightnode nnrpd[2480545]: 69-5-169-211.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 31 06:51:54 twilightnode nnrpd[2480545]: 69-5-169-211.infrawat.ch match_user <anonymous> <anonymous>
Aug 31 06:51:54 twilightnode nnrpd[2480545]: 69-5-169-211.infrawat.ch no_post anonymous-read
Aug 31 06:52:07 twilightnode nnrpd[2480552]: Reading access from /usr/local/news/etc/readers.conf
Aug 31 06:52:07 twilightnode nnrpd[2480552]: Auth strategy 'localhost' does not match client. Removing.
Aug 31 06:52:07 twilightnode nnrpd[2480552]: 81-19-219-226.infrawat.ch res <anonymous>
Aug 31 06:52:07 twilightnode nnrpd[2480552]: 81-19-219-226.infrawat.ch no_match_user <anonymous> <localhost>
Aug 31 06:52:07 twilightnode nnrpd[2480552]: 81-19-219-226.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 31 06:52:07 twilightnode nnrpd[2480552]: 81-19-219-226.infrawat.ch match_user <anonymous> <anonymous>
Aug 31 06:52:07 twilightnode nnrpd[2480552]: 81-19-219-226.infrawat.ch no_post anonymous-read
Aug 31 08:31:02 twilightnode nnrpd[2484712]: Reading access from /usr/local/news/etc/readers.conf
Aug 31 08:31:02 twilightnode nnrpd[2484712]: Auth strategy 'localhost' does not match client. Removing.
Aug 31 08:31:02 twilightnode nnrpd[2484712]: 194-88-98-90.infrawat.ch res <anonymous>
Aug 31 08:31:02 twilightnode nnrpd[2484712]: 194-88-98-90.infrawat.ch no_match_user <anonymous> <localhost>
Aug 31 08:31:02 twilightnode nnrpd[2484712]: 194-88-98-90.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 31 08:31:02 twilightnode nnrpd[2484712]: 194-88-98-90.infrawat.ch match_user <anonymous> <anonymous>
Aug 31 08:31:02 twilightnode nnrpd[2484712]: 194-88-98-90.infrawat.ch no_post anonymous-read
Aug 31 08:31:07 twilightnode nnrpd[2484713]: Reading access from /usr/local/news/etc/readers.conf
Aug 31 08:31:07 twilightnode nnrpd[2484713]: Auth strategy 'localhost' does not match client. Removing.
Aug 31 08:31:07 twilightnode nnrpd[2484713]: 5-226-140-105.infrawat.ch res <anonymous>
Aug 31 08:31:07 twilightnode nnrpd[2484713]: 5-226-140-105.infrawat.ch no_match_user <anonymous> <localhost>
Aug 31 08:31:07 twilightnode nnrpd[2484713]: 5-226-140-105.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 31 08:31:07 twilightnode nnrpd[2484713]: 5-226-140-105.infrawat.ch match_user <anonymous> <anonymous>
Aug 31 08:31:07 twilightnode nnrpd[2484713]: 5-226-140-105.infrawat.ch no_post anonymous-read
Aug 31 12:33:32 twilightnode nnrpd[2494962]: Reading access from /usr/local/news/etc/readers.conf
Aug 31 12:33:32 twilightnode nnrpd[2494962]: Auth strategy 'localhost' does not match client. Removing.
Aug 31 12:33:32 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com res <anonymous>
Aug 31 12:33:32 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 31 12:33:32 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 31 12:33:32 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 31 12:33:32 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com no_post anonymous-read
Aug 31 12:33:34 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com res <anonymous>
Aug 31 12:33:34 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 31 12:33:34 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 31 12:33:34 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 31 12:33:34 twilightnode nnrpd[2494962]: 190.172.132.66.censys-scanner.com no_post anonymous-read
Aug 31 14:49:03 twilightnode nnrpd[2500562]: Reading access from /usr/local/news/etc/readers.conf
Aug 31 14:49:03 twilightnode nnrpd[2500562]: Auth strategy 'localhost' does not match client. Removing.
Aug 31 14:49:03 twilightnode nnrpd[2500562]: 47.91.31.231 res <anonymous>
Aug 31 14:49:03 twilightnode nnrpd[2500562]: 47.91.31.231 no_match_user <anonymous> <localhost>
Aug 31 14:49:03 twilightnode nnrpd[2500562]: 47.91.31.231 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 31 14:49:03 twilightnode nnrpd[2500562]: 47.91.31.231 match_user <anonymous> <anonymous>
Aug 31 14:49:03 twilightnode nnrpd[2500562]: 47.91.31.231 no_post anonymous-read
Aug 31 16:51:58 twilightnode nnrpd[2507466]: Reading access from /usr/local/news/etc/readers.conf
Aug 31 16:51:58 twilightnode nnrpd[2507466]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7751 56.3%1.4 MB 61.8%
innd 4606 33.5%644.7 KB 28.8%
innfeed 1261 9.2%196.1 KB 8.7%
nnrpd 150 1.1%15.2 KB 0.7%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 13769 100.0%2.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20386 52.2%
Negative hits 10949 28.0%
Do not exist 7695 19.7%
Cache misses 8 0.0%
TOTAL: 4 39038 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.068 0.0%13 0.500 5.231 23.000
article cleanup00:00:00.062 0.0%7711 0.000 0.008 0.105
article logging00:00:00.204 0.0%7738 0.000 0.026 0.150
article parse00:00:00.122 0.0%10908 0.000 0.011 0.057
article write00:00:06.190 0.0%6466 0.597 0.957 2.282
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.211 0.0%41471 0.000 0.005 0.026
hisgrep/artcncl00:00:00.005 0.0%13 0.000 0.385 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.907 0.0%39122 0.053 0.100 0.238
history sync00:00:00.044 0.0%384 0.000 0.115 2.000
history write00:00:03.116 0.0%7711 0.160 0.404 0.795
idle23:50:23.886100.0%42133 965.467 2036.975 9383.453
nntp read00:00:00.844 0.0%41124 0.000 0.021 0.069
overview write00:00:04.074 0.0%6466 0.298 0.630 2.115
perl filter00:00:11.117 0.0%7711 0.679 1.442 4.528
python filter00:00:00.741 0.0%7711 0.000 0.096 0.389
site send00:00:00.295 0.0%12887 0.000 0.023 0.080
TOTAL: 1823:50:54.886100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.021 0.0%7016 0.000 0.003 0.095
article prepare00:00:00.004 0.0%29124 0.000 0.000 0.007
article read00:00:00.930 0.0%4459 0.000 0.209 1.067
backlog stats00:00:00.059 0.0%84717 0.000 0.001 0.010
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.639 0.0%47942 0.000 0.013 0.047
data write00:00:00.915 0.0%41699 0.000 0.022 0.063
idle23:49:52.449100.0%84717 378.368 1012.695 3176.794
status file00:00:00.141 0.0%166 0.000 0.849 6.000
TOTAL: 923:49:55.158100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:13.484100.0%21 15.000 642.095 2139.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%83 0.000 0.024 0.500
TOTAL: 300:00:13.486100.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 4 11397 5068 6269 60 44%46:58:35
2usenet.blueworldhosting.com 715 7658 974 5585 1099 12%23:48:03
3newsfeed.endofthelinebbs.com 3 3751 155 3526 70 4%17:52:34
4news.tebibyte.org 1 3124 60 3061 3 1%23:59:54
5news.sklaffkom.se 29 44 41 3 0 93%05:54:17
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 777 25974 6298 18444 1232 24%118:33:23
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net16.1 MB0.0 KB115.4 KB16.3 MB 99%3.2 KB
2usenet.blueworldhosting.com6.0 MB0.0 KB1.9 MB8.0 MB 75%3.9 KB
3newsfeed.endofthelinebbs.com2.3 MB0.0 KB105.2 KB2.4 MB 95%10.9 KB
4news.tebibyte.org253.2 KB0.0 KB15.0 KB268.2 KB 94%4.3 KB
5news.sklaffkom.se93.3 KB0.0 KB0.0 KB93.3 KB100%2.3 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 624.8 MB0.0 KB2.2 MB27.0 MB 91%3.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 31 03:00:04 - 03:59:59 130 2.0% 0.04448.9 KB 1.6% 0.12
Aug 31 04:00:00 - 04:59:59 129 2.0% 0.04803.6 KB 2.9% 0.22
Aug 31 05:00:00 - 05:59:59 141 2.2% 0.04754.7 KB 2.8% 0.21
Aug 31 06:00:00 - 06:59:59 147 2.3% 0.04580.8 KB 2.1% 0.16
Aug 31 07:00:00 - 07:59:59 164 2.5% 0.05682.2 KB 2.5% 0.19
Aug 31 08:00:00 - 08:59:59 207 3.2% 0.06575.9 KB 2.1% 0.16
Aug 31 09:00:00 - 09:59:59 311 4.8% 0.091.0 MB 3.7% 0.28
Aug 31 10:00:00 - 10:59:59 325 5.0% 0.091.2 MB 4.5% 0.34
Aug 31 11:00:00 - 11:59:59 347 5.4% 0.101.3 MB 4.7% 0.36
Aug 31 12:00:00 - 12:59:59 355 5.5% 0.101.4 MB 5.2% 0.40
Aug 31 13:00:00 - 13:59:59 346 5.3% 0.101.2 MB 4.6% 0.35
Aug 31 14:00:00 - 14:59:59 307 4.7% 0.091.3 MB 4.8% 0.37
Aug 31 15:00:00 - 15:59:59 375 5.8% 0.101.4 MB 5.2% 0.39
Aug 31 16:00:00 - 16:59:59 344 5.3% 0.101.3 MB 5.0% 0.38
Aug 31 17:00:00 - 17:59:59 418 6.5% 0.121.8 MB 6.8% 0.51
Aug 31 18:00:00 - 18:59:59 394 6.1% 0.111.6 MB 6.0% 0.45
Aug 31 19:00:00 - 19:59:59 379 5.9% 0.111.6 MB 5.9% 0.45
Aug 31 20:00:00 - 20:59:59 312 4.8% 0.091.4 MB 5.1% 0.39
Aug 31 21:00:00 - 21:59:59 307 4.7% 0.091.5 MB 5.5% 0.42
Aug 31 22:00:00 - 22:59:59 239 3.7% 0.071.2 MB 4.4% 0.33
Aug 31 23:00:00 - 23:59:59 226 3.5% 0.061.3 MB 4.9% 0.37
Sep 1 00:00:00 - 00:59:59 251 3.9% 0.071.1 MB 4.0% 0.31
Sep 1 01:00:00 - 01:59:59 174 2.7% 0.05820.3 KB 3.0% 0.23
Sep 1 02:00:00 - 02:59:59 140 2.2% 0.04687.7 KB 2.5% 0.19
Sep 1 03:00:00 - 03:00:03 9 0.1% 3.008.6 KB 0.0% 2.87
TOTAL: 23:59:59 6477 100.0% 0.0726.7 MB 100.0% 0.32
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1099 365 0 0 0 0 0 0 734
2newsfeed.endofthelinebbs.com 88 7 0 0 0 0 0 0 81
3twilightnode-out.news.weretis.net 86 11 0 0 0 0 0 0 75
4news.tebibyte.org 1 0 0 0 0 0 0 0 1
TOTAL: 4 1274 383 0 0 0 0 0 0 891

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 48
hispagatos.mastodon.tag.hacking 8
wolfsburg.test 4
alt.sex.pedophilia 2
de.comm.software.mailserver 2
fido.ru.fidonet.today 2
fido.ru.space 2
kraft.politics 2
local.general 2
alt.accounting 1
alt.antiques 1
alt.bbs.files-announce 1
alt.bin 1
alt.chess 1
alt.chinese 1
alt.clubs 1
alt.coupons 1
alt.crafts 1
alt.creative.writing 1
TOTAL: 33 383

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 631
EMP (fsl) 122
Too many newsgroups 73
User-issued cancel 32
HTML Multipart 3
Too many newsgroups (meow) 2
Binary: misplaced binary 1
TOTAL: 7 864

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8143 2844 3458 98 30 79 34%23:59:55
2sklaffkom.se 6605 1134 2088 3179 31 0 17%23:59:39
3newsfeed.endofthelinebbs.com 11579 11 6112 5 1 462 0%23:59:55
4weretis.net 1408 4 1365 0 0 0 0%23:37:55
5usenet.blueworldhosting.com 8662 3 5103 0 0 70 0%23:59:55
TOTAL: 5 36397 3996 18126 3282 62 611 10%119:37:19
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org8.1 MB629.9 KB8.8 MB0.1 KB/s3.0 KB23:59:55
2sklaffkom.se1.8 MB17.2 MB19.0 MB0.2 KB/s4.5 KB23:59:39
3newsfeed.endofthelinebbs.com29.7 KB21.6 KB51.3 KB0.0 KB/s3.2 KB23:59:55
4usenet.blueworldhosting.com8.5 KB0.0 KB8.5 KB0.0 KB/s2.8 KB23:59:55
5weretis.net5.2 KB0.0 KB5.2 KB0.0 KB/s1.3 KB23:37:55
TOTAL: 510.0 MB17.8 MB27.8 MB0.1 KB/s3.9 KB119:37:19
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1188.166.87.138 4 00.0 KB 0 0 000:00:04
2190.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:04
3194-88-98-90.infrawat.ch 1 00.0 KB 0 0 000:00:00
4198.235.24.120 1 00.0 KB 0 0 000:00:00
547.91.31.231 1 00.0 KB 0 0 000:00:03
65-226-140-105.infrawat.ch 1 00.0 KB 0 0 000:00:00
769-5-169-211.infrawat.ch 1 00.0 KB 0 0 000:00:00
881-19-219-226.infrawat.ch 1 00.0 KB 0 0 000:00:00
TOTAL: 8 11 00.0 KB 0 0 000:00:13

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 6 00.0 KB 0 0 000:00:08
2*.infrawat.ch 4 00.0 KB 0 0 000:00:01
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:04
TOTAL: 3 11 00.0 KB 0 0 000:00:13

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
188.166.87.138 0.012 0.027 0.00000:00:04
190.172.132.66.censys-scanner.com 0.028 0.016 0.00000:00:04
47.91.31.231 0.002 0.019 0.00000:00:03
198.235.24.120 0.003 0.011 0.00000:00:00
81-19-219-226.infrawat.ch 0.003 0.008 0.00000:00:00
5-226-140-105.infrawat.ch 0.007 0.007 0.00000:00:00
69-5-169-211.infrawat.ch 0.004 0.017 0.00000:00:00
194-88-98-90.infrawat.ch 0.007 0.015 0.00000:00:00
TOTAL: 8 0.066 0.120 0.00000:00:13

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

SystemConn
188.166.87.138 9
47.91.31.231 3
190.172.132.66.censys-scanner.com 1
TOTAL: 3 13

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

CommandCount
#002$?#025#011#022?s?}?#024d?#001?7?1u?Tp`?#014?%w?#[X?... 1
#023#001#023#002#023#003#001 1
#023#003#023#001#023#002#001 1
#026#003#001 1
#026#003#001#005?#001 1
#?I&?o?Z?QS? 1
?#016?a<#027?z/?L?#016?1c[D#007?#006?w?... 1
?#020?j?Ns?'#011#015?+?rB?`#004?E?#023uPEa?... 1
?#024 1
?#024y?zr?v~!?:1?#027?D#001L?f?d?p?#7e#034?;... 1
C?\)?@J? 1
EHLO www.censys.io 1
Y 1
TOTAL: 13 13

NNRP client timeouts [Top 20]:

SystemConnPeer
188.166.87.138 1 2
47.91.31.231 1 1
5-226-140-105.infrawat.ch 1 1
81-19-219-226.infrawat.ch 1 1
TOTAL: 4 4 8