Daily Usenet report

Sep 9 03:00:04 -- Sep 10 03:00:03

Unknown entries from news log file:

First 50 / 67 lines (74.6%)

Sep 9 03:01:02 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 9 03:38:03 twilightnode nnrpd[3086171]: Reading access from /usr/local/news/etc/readers.conf
Sep 9 03:38:03 twilightnode nnrpd[3086171]: Auth strategy 'localhost' does not match client. Removing.
Sep 9 03:38:03 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com res <anonymous>
Sep 9 03:38:03 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 9 03:38:03 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 9 03:38:03 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 9 03:38:03 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com no_post anonymous-read
Sep 9 03:38:04 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com res <anonymous>
Sep 9 03:38:04 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 9 03:38:04 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 9 03:38:04 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 9 03:38:04 twilightnode nnrpd[3086171]: 219.172.132.66.censys-scanner.com no_post anonymous-read
Sep 9 05:04:24 twilightnode nnrpd[3090041]: Reading access from /usr/local/news/etc/readers.conf
Sep 9 05:04:24 twilightnode nnrpd[3090041]: Auth strategy 'localhost' does not match client. Removing.
Sep 9 05:04:24 twilightnode nnrpd[3090041]: o007.scanner.modat.io res <anonymous>
Sep 9 05:04:24 twilightnode nnrpd[3090041]: o007.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 9 05:04:24 twilightnode nnrpd[3090041]: o007.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 9 05:04:24 twilightnode nnrpd[3090041]: o007.scanner.modat.io match_user <anonymous> <anonymous>
Sep 9 05:04:24 twilightnode nnrpd[3090041]: o007.scanner.modat.io no_post anonymous-read
Sep 9 06:25:08 twilightnode nnrpd[3093762]: Reading access from /usr/local/news/etc/readers.conf
Sep 9 06:25:08 twilightnode nnrpd[3093762]: Auth strategy 'localhost' does not match client. Removing.
Sep 9 06:25:08 twilightnode nnrpd[3093762]: 185.242.226.72 res <anonymous>
Sep 9 06:25:08 twilightnode nnrpd[3093762]: 185.242.226.72 no_match_user <anonymous> <localhost>
Sep 9 06:25:08 twilightnode nnrpd[3093762]: 185.242.226.72 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 9 06:25:08 twilightnode nnrpd[3093762]: 185.242.226.72 match_user <anonymous> <anonymous>
Sep 9 06:25:08 twilightnode nnrpd[3093762]: 185.242.226.72 no_post anonymous-read
Sep 9 06:49:28 twilightnode nnrpd[3094876]: Reading access from /usr/local/news/etc/readers.conf
Sep 9 06:49:28 twilightnode nnrpd[3094876]: Auth strategy 'localhost' does not match client. Removing.
Sep 9 06:49:28 twilightnode nnrpd[3094876]: 183.186.132.66.censys-scanner.com res <anonymous>
Sep 9 06:49:28 twilightnode nnrpd[3094876]: 183.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 9 06:49:28 twilightnode nnrpd[3094876]: 183.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 9 06:49:28 twilightnode nnrpd[3094876]: 183.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 9 06:49:28 twilightnode nnrpd[3094876]: 183.186.132.66.censys-scanner.com no_post anonymous-read
Sep 9 06:49:35 twilightnode nnrpd[3094879]: Reading access from /usr/local/news/etc/readers.conf
Sep 9 06:49:35 twilightnode nnrpd[3094879]: Auth strategy 'localhost' does not match client. Removing.
Sep 9 06:49:35 twilightnode nnrpd[3094879]: 183.186.132.66.censys-scanner.com res <anonymous>
Sep 9 06:49:35 twilightnode nnrpd[3094879]: 183.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 9 06:49:35 twilightnode nnrpd[3094879]: 183.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 9 06:49:35 twilightnode nnrpd[3094879]: 183.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 9 06:49:35 twilightnode nnrpd[3094879]: 183.186.132.66.censys-scanner.com no_post anonymous-read
Sep 9 06:49:42 twilightnode nnrpd[3094880]: Reading access from /usr/local/news/etc/readers.conf
Sep 9 06:49:42 twilightnode nnrpd[3094880]: Auth strategy 'localhost' does not match client. Removing.
Sep 9 06:49:42 twilightnode nnrpd[3094880]: 183.186.132.66.censys-scanner.com res <anonymous>
Sep 9 06:49:42 twilightnode nnrpd[3094880]: 183.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 9 06:49:42 twilightnode nnrpd[3094880]: 183.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 9 06:49:42 twilightnode nnrpd[3094880]: 183.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 9 06:49:42 twilightnode nnrpd[3094880]: 183.186.132.66.censys-scanner.com no_post anonymous-read
Sep 9 06:49:43 twilightnode nnrpd[3094880]: 183.186.132.66.censys-scanner.com res <anonymous>
Sep 9 06:49:43 twilightnode nnrpd[3094880]: 183.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>

Log entries by program:

Program nameLines%LinesSize%Size
inn 7818 55.4%1.3 MB 60.8%
innd 4940 35.0%684.5 KB 30.2%
innfeed 1251 8.9%193.6 KB 8.5%
nnrpd 103 0.7%10.8 KB 0.5%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 14113 100.0%2.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20548 51.7%
Negative hits 11424 28.7%
Do not exist 7767 19.5%
Cache misses 2 0.0%
TOTAL: 4 39741 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.178 0.0%35 0.300 5.086 32.000
article cleanup00:00:00.059 0.0%7777 0.000 0.008 0.133
article logging00:00:00.251 0.0%7818 0.000 0.032 0.167
article parse00:00:00.115 0.0%10477 0.000 0.011 0.056
article write00:00:06.449 0.0%6264 0.578 1.030 2.469
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.221 0.0%42208 0.000 0.005 0.039
hisgrep/artcncl00:00:00.022 0.0%35 0.000 0.629 1.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.135 0.0%39805 0.033 0.104 0.203
history sync00:00:00.034 0.0%306 0.000 0.111 1.000
history write00:00:03.242 0.0%7779 0.196 0.417 0.750
idle23:59:46.880 99.9%43060 866.841 2006.198 7228.193
nntp read00:00:00.841 0.0%42125 0.000 0.020 0.073
overview write00:00:03.930 0.0%6264 0.235 0.627 2.071
perl filter00:00:12.558 0.0%7777 0.840 1.615 8.818
python filter00:00:00.877 0.0%7777 0.016 0.113 0.789
site send00:00:00.280 0.0%12495 0.000 0.022 0.143
TOTAL: 1824:00:20.072100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%6457 0.000 0.003 0.071
article prepare00:00:00.006 0.0%28246 0.000 0.000 0.008
article read00:00:00.966 0.0%4485 0.000 0.215 4.000
backlog stats00:00:00.044 0.0%85157 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.595 0.0%47526 0.000 0.013 0.037
data write00:00:00.954 0.0%41374 0.000 0.023 0.057
idle23:50:35.349100.0%85157 363.668 1007.966 3610.367
status file00:00:00.136 0.0%161 0.000 0.845 3.000
TOTAL: 923:50:38.068100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:06.602100.0%15 103.667 440.133 1353.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%80 0.000 0.000 0.000
TOTAL: 300:00:06.602100.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 3 11505 4721 6563 221 41%48:31:42
2usenet.blueworldhosting.com 716 7653 1178 5341 1134 15%23:45:50
3newsfeed.endofthelinebbs.com 1 5814 294 5389 131 5%26:39:34
4news.sklaffkom.se 48 146 127 19 0 86%14:28:20
5news.tebibyte.org 1 3597 60 3536 1 1%23:59:51
6localhost 25 0 0 0 0 0%00:00:00
TOTAL: 6 794 28715 6380 20848 1487 22%137:25:17
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.6 MB0.0 KB551.8 KB15.2 MB 96%3.1 KB
2usenet.blueworldhosting.com6.2 MB0.0 KB1.5 MB7.7 MB 80%3.4 KB
3newsfeed.endofthelinebbs.com3.6 MB0.0 KB211.9 KB3.8 MB 94%9.2 KB
4news.sklaffkom.se477.9 KB0.0 KB0.0 KB477.9 KB100%3.8 KB
5news.tebibyte.org301.2 KB0.0 KB2.2 KB303.4 KB 99%5.0 KB
6localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 625.3 MB0.0 KB2.2 MB27.5 MB 91%3.6 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 9 03:00:04 - 03:59:59 93 1.5% 0.03481.7 KB 1.9% 0.13
Sep 9 04:00:00 - 04:59:59 117 1.9% 0.03489.0 KB 2.0% 0.14
Sep 9 05:00:00 - 05:59:59 109 1.7% 0.03413.2 KB 1.7% 0.11
Sep 9 06:00:00 - 06:59:59 184 2.9% 0.05695.6 KB 2.8% 0.19
Sep 9 07:00:00 - 07:59:59 153 2.4% 0.04538.2 KB 2.2% 0.15
Sep 9 08:00:00 - 08:59:59 229 3.7% 0.061.1 MB 4.4% 0.30
Sep 9 09:00:00 - 09:59:59 327 5.2% 0.091009.0 KB 4.1% 0.28
Sep 9 10:00:00 - 10:59:59 331 5.3% 0.091.0 MB 4.3% 0.30
Sep 9 11:00:00 - 11:59:59 383 6.1% 0.111002.1 KB 4.1% 0.28
Sep 9 12:00:00 - 12:59:59 304 4.9% 0.081.3 MB 5.4% 0.37
Sep 9 13:00:00 - 13:59:59 307 4.9% 0.09983.8 KB 4.0% 0.27
Sep 9 14:00:00 - 14:59:59 317 5.1% 0.091.0 MB 4.3% 0.29
Sep 9 15:00:00 - 15:59:59 420 6.7% 0.121.6 MB 6.5% 0.44
Sep 9 16:00:00 - 16:59:59 348 5.6% 0.101.2 MB 5.0% 0.34
Sep 9 17:00:00 - 17:59:59 337 5.4% 0.091.2 MB 4.8% 0.33
Sep 9 18:00:00 - 18:59:59 307 4.9% 0.091.2 MB 4.8% 0.33
Sep 9 19:00:00 - 19:59:59 342 5.5% 0.101.4 MB 6.0% 0.41
Sep 9 20:00:00 - 20:59:59 254 4.1% 0.071.2 MB 5.0% 0.34
Sep 9 21:00:00 - 21:59:59 284 4.5% 0.081.3 MB 5.3% 0.36
Sep 9 22:00:00 - 22:59:59 290 4.6% 0.081.4 MB 6.0% 0.41
Sep 9 23:00:00 - 23:59:59 224 3.6% 0.061.2 MB 5.1% 0.35
Sep 10 00:00:00 - 00:59:59 311 5.0% 0.091.4 MB 5.7% 0.39
Sep 10 01:00:00 - 01:59:59 178 2.8% 0.05706.8 KB 2.9% 0.20
Sep 10 02:00:00 - 02:59:59 104 1.7% 0.03504.0 KB 2.0% 0.14
Sep 10 03:00:00 - 03:00:03 9 0.1% 3.007.6 KB 0.0% 2.52
TOTAL: 23:59:59 6262 100.0% 0.0724.1 MB 100.0% 0.29
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1134 334 0 0 0 0 0 0 800
2twilightnode-out.news.weretis.net 283 18 0 0 0 0 0 0 265
3newsfeed.endofthelinebbs.com 139 15 0 0 0 0 0 0 124
TOTAL: 3 1556 367 0 0 0 0 0 0 1189

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.test.ping 288
hispagatos.hacking.ransomware 22
fido.ru.fidonet.today 8
ad.proves 5
atl.forsale 4
alt.drwho 3
alt.forsale.seattle 3
nyc.forsale 3
uk.comp.misc 3
alt.sports.football.pro.gb-packers 2
chi.forsale 2
de.comm.abuse 2
oc.forsale 2
upenn.forsale 2
van.forsale 2
3dfx.products.voodoobanshee 1
alt.2600.madrid 1
alt.anarchism.syndicalist 1
alt.bbs.files-announce 1
austin.forsale 1
TOTAL: 31 367

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 822
EMP (phn path) 154
Too many newsgroups 102
User-issued cancel 59
Too many newsgroups (meow) 6
HTML Multipart 3
TOTAL: 6 1146

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 41
TOTAL: 1 41
No colon-space in header field
usenet.blueworldhosting.com 2
TOTAL: 1 2
TOTAL: 2 43

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 8248 2517 3540 77 19 83 30%23:59:55
2sklaffkom.se 6379 1138 1896 3174 22 0 17%23:59:55
3newsfeed.endofthelinebbs.com 10993 38 5868 96 0 233 0%23:59:20
4weretis.net 1841 8 1696 0 0 0 0%23:58:29
5usenet.blueworldhosting.com 8059 6 4613 0 0 5 0%23:59:55
TOTAL: 5 35520 3707 17613 3347 41 321 10%119:57:34
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.7 MB1.1 MB7.8 MB0.1 KB/s3.1 KB23:59:55
2sklaffkom.se1.9 MB15.2 MB17.0 MB0.2 KB/s4.0 KB23:59:55
3newsfeed.endofthelinebbs.com119.6 KB375.1 KB494.7 KB0.0 KB/s3.7 KB23:59:20
4usenet.blueworldhosting.com20.3 KB0.0 KB20.3 KB0.0 KB/s3.4 KB23:59:55
5weretis.net18.6 KB0.0 KB18.6 KB0.0 KB/s2.3 KB23:58:29
TOTAL: 58.8 MB16.6 MB25.4 MB0.1 KB/s3.7 KB119:57:34
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1183.186.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
2185.242.226.72 1 00.0 KB 0 0 000:00:00
3212.77.178.125 1 00.0 KB 0 0 000:00:00
4219.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
586.54.31.40 1 00.0 KB 0 0 000:00:00
6o007.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 6 8 00.0 KB 0 0 000:00:06

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:05
2unresolved 3 00.0 KB 0 0 000:00:00
3*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 3 8 00.0 KB 0 0 000:00:06

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
183.186.132.66.censys-scanner.com 0.032 0.037 0.00000:00:04
219.172.132.66.censys-scanner.com 0.029 0.015 0.00000:00:01
212.77.178.125 0.004 0.013 0.00000:00:00
o007.scanner.modat.io 0.002 0.017 0.00000:00:00
185.242.226.72 0.004 0.010 0.00000:00:00
86.54.31.40 0.005 0.008 0.00000:00:00
TOTAL: 6 0.076 0.100 0.00000:00:06

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

SystemConn
185.242.226.72 2
o007.scanner.modat.io 2
183.186.132.66.censys-scanner.com 1
219.172.132.66.censys-scanner.com 1
TOTAL: 4 6

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

CommandCount
EHLO www.censys.io 2
#001 1
#026#003#001 1
FEAT 1
SYST 1
TOTAL: 5 6

NNRP client timeouts [Top 20]:

SystemConnPeer
183.186.132.66.censys-scanner.com 1 2
TOTAL: 1 1 2
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