Daily Usenet report

Jun 29 03:00:05 -- Jun 30 03:00:06

Unknown entries from news log file:

First 50 / 102 lines (49.0%)

Jun 29 03:01:21 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 29 07:29:36 twilightnode nnrpd[2875494]: Reading access from /usr/local/news/etc/readers.conf
Jun 29 07:29:36 twilightnode nnrpd[2875494]: Auth strategy 'localhost' does not match client. Removing.
Jun 29 07:29:36 twilightnode nnrpd[2875494]: 50.146.94.167.censys-scanner.com res <anonymous>
Jun 29 07:29:36 twilightnode nnrpd[2875494]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 29 07:29:36 twilightnode nnrpd[2875494]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 29 07:29:36 twilightnode nnrpd[2875494]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jun 29 07:29:36 twilightnode nnrpd[2875494]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Jun 29 07:29:39 twilightnode nnrpd[2875495]: Reading access from /usr/local/news/etc/readers.conf
Jun 29 07:29:39 twilightnode nnrpd[2875495]: Auth strategy 'localhost' does not match client. Removing.
Jun 29 07:29:39 twilightnode nnrpd[2875495]: 50.146.94.167.censys-scanner.com res <anonymous>
Jun 29 07:29:39 twilightnode nnrpd[2875495]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 29 07:29:39 twilightnode nnrpd[2875495]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 29 07:29:39 twilightnode nnrpd[2875495]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jun 29 07:29:39 twilightnode nnrpd[2875495]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Jun 29 07:29:45 twilightnode nnrpd[2875496]: Reading access from /usr/local/news/etc/readers.conf
Jun 29 07:29:45 twilightnode nnrpd[2875496]: Auth strategy 'localhost' does not match client. Removing.
Jun 29 07:29:45 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com res <anonymous>
Jun 29 07:29:45 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 29 07:29:45 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 29 07:29:45 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jun 29 07:29:45 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Jun 29 07:29:46 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com res <anonymous>
Jun 29 07:29:46 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 29 07:29:46 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 29 07:29:46 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jun 29 07:29:46 twilightnode nnrpd[2875496]: 50.146.94.167.censys-scanner.com no_post anonymous-read
Jun 29 07:42:33 twilightnode nnrpd[2876079]: Reading access from /usr/local/news/etc/readers.conf
Jun 29 07:42:33 twilightnode nnrpd[2876079]: Auth strategy 'localhost' does not match client. Removing.
Jun 29 07:42:33 twilightnode nnrpd[2876079]: 195.206.182.201 res <anonymous>
Jun 29 07:42:33 twilightnode nnrpd[2876079]: 195.206.182.201 no_match_user <anonymous> <localhost>
Jun 29 07:42:33 twilightnode nnrpd[2876079]: 195.206.182.201 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 29 07:42:33 twilightnode nnrpd[2876079]: 195.206.182.201 match_user <anonymous> <anonymous>
Jun 29 07:42:33 twilightnode nnrpd[2876079]: 195.206.182.201 no_post anonymous-read
Jun 29 07:42:40 twilightnode nnrpd[2876080]: Reading access from /usr/local/news/etc/readers.conf
Jun 29 07:42:40 twilightnode nnrpd[2876080]: Auth strategy 'localhost' does not match client. Removing.
Jun 29 07:42:40 twilightnode nnrpd[2876080]: 188.240.59.27 res <anonymous>
Jun 29 07:42:40 twilightnode nnrpd[2876080]: 188.240.59.27 no_match_user <anonymous> <localhost>
Jun 29 07:42:40 twilightnode nnrpd[2876080]: 188.240.59.27 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 29 07:42:40 twilightnode nnrpd[2876080]: 188.240.59.27 match_user <anonymous> <anonymous>
Jun 29 07:42:40 twilightnode nnrpd[2876080]: 188.240.59.27 no_post anonymous-read
Jun 29 18:21:49 twilightnode nnrpd[2904561]: Reading access from /usr/local/news/etc/readers.conf
Jun 29 18:21:49 twilightnode nnrpd[2904561]: Auth strategy 'localhost' does not match client. Removing.
Jun 29 18:21:49 twilightnode nnrpd[2904561]: 198.235.24.87 res <anonymous>
Jun 29 18:21:49 twilightnode nnrpd[2904561]: 198.235.24.87 no_match_user <anonymous> <localhost>
Jun 29 18:21:49 twilightnode nnrpd[2904561]: 198.235.24.87 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 29 18:21:49 twilightnode nnrpd[2904561]: 198.235.24.87 match_user <anonymous> <anonymous>
Jun 29 18:21:49 twilightnode nnrpd[2904561]: 198.235.24.87 no_post anonymous-read
Jun 29 19:21:13 twilightnode nnrpd[2907052]: Reading access from /usr/local/news/etc/readers.conf
Jun 29 19:21:13 twilightnode nnrpd[2907052]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 41200 82.2%5.7 MB 78.2%
inn 7173 14.3%1.3 MB 17.7%
innd 1576 3.1%286.3 KB 3.9%
nnrpd 161 0.3%18.0 KB 0.2%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 50112 100.0%7.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15711 48.7%
Negative hits 9370 29.1%
Do not exist 7144 22.2%
Cache misses 5 0.0%
TOTAL: 4 32230 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.005 0.0%3 1.000 1.667 3.000
article cleanup00:00:00.070 0.0%7153 0.000 0.010 0.138
article logging00:00:00.264 0.0%7174 0.000 0.037 0.167
article parse00:00:00.097 0.0%11850 0.000 0.008 0.133
article write00:00:04.091 0.0%4398 0.435 0.930 2.857
data move00:00:00.121 0.0%34977 0.000 0.003 0.052
hisgrep/artcncl00:00:00.003 0.0%3 0.000 1.000 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.741 0.0%32294 0.078 0.147 0.482
history sync00:00:00.067 0.0%410 0.000 0.163 1.333
history write00:00:03.610 0.0%7153 0.214 0.505 1.174
idle24:00:31.566100.0%34269 292.714 2522.150 11334.321
nntp read00:00:00.843 0.0%33943 0.000 0.025 0.076
overview write00:00:02.327 0.0%4398 0.200 0.529 2.111
perl filter00:00:11.550 0.0%7153 0.641 1.615 24.000
python filter00:00:00.889 0.0%7153 0.000 0.124 1.261
site send00:00:00.127 0.0%4398 0.000 0.029 0.208
TOTAL: 1724:01:00.371100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.038 0.0%9991 0.000 0.004 0.043
article prepare00:00:00.003 0.0%26244 0.000 0.000 0.028
article read00:00:00.730 0.0%4386 0.000 0.166 0.846
backlog stats00:00:00.071 0.0%68653 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.526 0.0%33328 0.000 0.016 0.047
data write00:00:00.876 0.0%29014 0.000 0.030 0.081
idle23:57:58.328100.0%68653 891.366 1256.731 1711.839
status file00:00:09.803 0.0%17020 0.402 0.576 0.770
TOTAL: 923:58:10.375100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:19.034100.0%19 51.500 1001.789 10280.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%141 0.000 0.000 0.000
TOTAL: 300:00:19.034100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11299 3766 6037 1496 33%48:34:41
2newsfeed.endofthelinebbs.com 1 5687 482 4503 702 8%25:44:54
3news.tebibyte.org 2 11625 156 10953 516 1%40:50:03
4news.sklaffkom.se 39 83 52 27 4 62%09:42:23
5localhost 25 0 0 0 0 0%00:00:00
6news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 6 72 28694 4456 21520 2718 15%125:02:01
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.4 MB0.0 KB4.2 MB15.6 MB 73%3.0 KB
2newsfeed.endofthelinebbs.com5.1 MB0.0 KB3.7 MB8.8 MB 57%7.6 KB
3news.tebibyte.org721.7 KB0.0 KB2.2 MB2.9 MB 24%4.4 KB
4news.sklaffkom.se246.9 KB0.0 KB7.5 KB254.4 KB 97%4.5 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
6news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 617.4 MB0.0 KB10.1 MB27.5 MB 63%3.9 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 29 03:00:05 - 03:59:59 63 1.4% 0.02198.8 KB 1.1% 0.06
Jun 29 04:00:00 - 04:59:59 96 2.2% 0.03447.2 KB 2.5% 0.12
Jun 29 05:00:00 - 05:59:59 121 2.8% 0.03417.5 KB 2.3% 0.12
Jun 29 06:00:00 - 06:59:59 111 2.5% 0.03391.8 KB 2.2% 0.11
Jun 29 07:00:00 - 07:59:59 130 3.0% 0.04387.2 KB 2.2% 0.11
Jun 29 08:00:00 - 08:59:59 158 3.6% 0.04540.0 KB 3.0% 0.15
Jun 29 09:00:00 - 09:59:59 222 5.0% 0.06691.9 KB 3.9% 0.19
Jun 29 10:00:00 - 10:59:59 247 5.6% 0.07791.1 KB 4.4% 0.22
Jun 29 11:00:00 - 11:59:59 269 6.1% 0.071.1 MB 6.3% 0.31
Jun 29 12:00:00 - 12:59:59 187 4.3% 0.05639.3 KB 3.6% 0.18
Jun 29 13:00:00 - 13:59:59 244 5.5% 0.071.0 MB 5.8% 0.29
Jun 29 14:00:00 - 14:59:59 240 5.5% 0.07886.5 KB 4.9% 0.25
Jun 29 15:00:00 - 15:59:59 259 5.9% 0.071.5 MB 8.5% 0.42
Jun 29 16:00:00 - 16:59:59 228 5.2% 0.06956.4 KB 5.3% 0.27
Jun 29 17:00:00 - 17:59:59 233 5.3% 0.06720.0 KB 4.0% 0.20
Jun 29 18:00:00 - 18:59:59 252 5.7% 0.07860.7 KB 4.8% 0.24
Jun 29 19:00:00 - 19:59:59 225 5.1% 0.06993.5 KB 5.5% 0.28
Jun 29 20:00:00 - 20:59:59 225 5.1% 0.06752.8 KB 4.2% 0.21
Jun 29 21:00:00 - 21:59:59 210 4.8% 0.06701.9 KB 3.9% 0.19
Jun 29 22:00:00 - 22:59:59 190 4.3% 0.05759.8 KB 4.2% 0.21
Jun 29 23:00:00 - 23:59:59 152 3.5% 0.041.7 MB 9.6% 0.48
Jun 30 00:00:00 - 00:59:59 89 2.0% 0.02391.6 KB 2.2% 0.11
Jun 30 01:00:00 - 01:59:59 126 2.9% 0.04430.3 KB 2.4% 0.12
Jun 30 02:00:00 - 02:59:59 109 2.5% 0.03581.0 KB 3.2% 0.16
Jun 30 03:00:00 - 03:00:06 11 0.3% 1.8311.5 KB 0.1% 1.92
TOTAL: 24:00:01 4397 100.0% 0.0517.5 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1495 1103 0 0 0 0 0 0 392
2newsfeed.endofthelinebbs.com 646 273 0 0 0 0 0 0 373
3news.tebibyte.org 631 573 0 0 0 0 0 0 58
4news.sklaffkom.se 4 4 0 0 0 0 0 0 0
TOTAL: 4 2776 1953 0 0 0 0 0 0 823

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.sex.pre-amateurs 361
alt.sex.amateur.fa 188
de.alt.talk.unmut 61
alt.sex.pedophilia.yakanoh 56
alt.sex.pedophile.mike-labbe 45
fido7.ru.home 45
pl.soc.prawo 40
de.talk.jokes 39
uk.net.news.moderation 35
linux.debian.maint.ocaml.maint 32
fido7.ukrnews 30
it.hobby.cucina 25
aus.politics 24
de.soc.recht.misc 24
fido7.ru.ftn.develop 23
alt.prophecies.nostradamus 22
alt.usage.english 22
lada.talk 21
hfx.general 20
linux.debian.maint.emacsen 19
TOTAL: 251 1953

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 393
EMP (md5) 382
User-issued cancel 18
Too many newsgroups 6
HTML Multipart 3
TOTAL: 5 802

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4398 4379 0 18 0 0 99%23:59:57
2sklaffkom.se 4673 853 2210 1327 0 1 18%23:59:40
3news.tebibyte.org 2838 0 0 0 0 7229 0%23:59:57
4newsfeed.endofthelinebbs.com 2774 0 0 0 0 6702 0%23:59:56
5weretis.net 646 0 620 0 0 0 0%21:55:37
6sklaff.dev 0 0 0 0 0 4391 0%23:59:07
7thebunker.nu 0 0 0 0 0 4397 0%23:59:57
8usenet.blueworldhosting.com 0 0 0 0 0 4103 0%23:59:57
TOTAL: 8 15329 5232 2830 1345 0 26823 34%189:54:08
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se17.5 MB44.5 KB17.5 MB0.2 KB/s4.1 KB23:59:57
2sklaffkom.se1.3 MB8.2 MB9.5 MB0.1 KB/s4.5 KB23:59:40
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:07
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s021:55:37
TOTAL: 818.8 MB8.2 MB27.0 MB0.0 KB/s4.2 KB189:54:08
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
150.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:03
255.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:03
3azpdcse6dy05.stretchoid.com 2 00.0 KB 0 0 000:00:10
4188.240.59.27 1 00.0 KB 0 0 000:00:00
5195.206.182.201 1 00.0 KB 0 0 000:00:00
6198.235.24.87 1 00.0 KB 0 0 000:00:00
7o318.scanner.modat.io 1 00.0 KB 0 0 000:00:00
8o325.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 8 13 00.0 KB 0 0 000:00:19

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.167.censys-scanner.com 6 00.0 KB 0 0 000:00:07
2unresolved 3 00.0 KB 0 0 000:00:00
3*.scanner.modat.io 2 00.0 KB 0 0 000:00:00
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
TOTAL: 4 13 00.0 KB 0 0 000:00:19

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcse6dy05.stretchoid.com 0.008 0.022 0.00000:00:10
50.146.94.167.censys-scanner.com 0.041 0.033 0.00000:00:03
55.146.94.167.censys-scanner.com 0.029 0.034 0.00000:00:03
198.235.24.87 0.003 0.014 0.00000:00:00
o325.scanner.modat.io 0.006 0.013 0.00000:00:00
o318.scanner.modat.io 0.002 0.015 0.00000:00:00
188.240.59.27 0.004 0.006 0.00000:00:00
195.206.182.201 0.007 0.020 0.00000:00:00
TOTAL: 8 0.100 0.157 0.00000:00:19

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

SystemConn
50.146.94.167.censys-scanner.com 1
55.146.94.167.censys-scanner.com 1
azpdcse6dy05.stretchoid.com 1
TOTAL: 3 3

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

CommandCount
EHLO www.censys.io 2
MGLNDD_172.234.115.63_119 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
188.240.59.27 1 1
50.146.94.167.censys-scanner.com 1 2
55.146.94.167.censys-scanner.com 1 2
TOTAL: 3 3 7
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