Daily Usenet report

Jul 1 03:00:06 -- Jul 2 03:00:05

Unknown entries from news log file:

First 35 / 35 lines (100.0%)

Jul 1 03:01:22 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 1 07:47:17 twilightnode nnrpd[3014810]: Reading access from /usr/local/news/etc/readers.conf
Jul 1 07:47:17 twilightnode nnrpd[3014810]: Auth strategy 'localhost' does not match client. Removing.
Jul 1 07:47:17 twilightnode nnrpd[3014810]: 195.140.214.20 res <anonymous>
Jul 1 07:47:17 twilightnode nnrpd[3014810]: 195.140.214.20 no_match_user <anonymous> <localhost>
Jul 1 07:47:17 twilightnode nnrpd[3014810]: 195.140.214.20 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 1 07:47:17 twilightnode nnrpd[3014810]: 195.140.214.20 match_user <anonymous> <anonymous>
Jul 1 07:47:17 twilightnode nnrpd[3014810]: 195.140.214.20 no_post anonymous-read
Jul 1 07:47:27 twilightnode nnrpd[3014813]: Reading access from /usr/local/news/etc/readers.conf
Jul 1 07:47:27 twilightnode nnrpd[3014813]: Auth strategy 'localhost' does not match client. Removing.
Jul 1 07:47:27 twilightnode nnrpd[3014813]: 213.166.84.58 res <anonymous>
Jul 1 07:47:27 twilightnode nnrpd[3014813]: 213.166.84.58 no_match_user <anonymous> <localhost>
Jul 1 07:47:27 twilightnode nnrpd[3014813]: 213.166.84.58 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 1 07:47:27 twilightnode nnrpd[3014813]: 213.166.84.58 match_user <anonymous> <anonymous>
Jul 1 07:47:27 twilightnode nnrpd[3014813]: 213.166.84.58 no_post anonymous-read
Jul 1 08:39:54 twilightnode nnrpd[3016922]: Reading access from /usr/local/news/etc/readers.conf
Jul 1 08:39:54 twilightnode nnrpd[3016922]: Auth strategy 'localhost' does not match client. Removing.
Jul 1 08:39:54 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com res <anonymous>
Jul 1 08:39:54 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 1 08:39:54 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 1 08:39:54 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 1 08:39:54 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com no_post anonymous-read
Jul 1 08:39:55 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com res <anonymous>
Jul 1 08:39:55 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 1 08:39:55 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 1 08:39:55 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 1 08:39:55 twilightnode nnrpd[3016922]: 105.172.132.66.censys-scanner.com no_post anonymous-read
Jul 1 14:37:01 twilightnode nnrpd[3031953]: Reading access from /usr/local/news/etc/readers.conf
Jul 1 14:37:01 twilightnode nnrpd[3031953]: Auth strategy 'localhost' does not match client. Removing.
Jul 1 14:37:01 twilightnode nnrpd[3031953]: 198.235.24.98 res <anonymous>
Jul 1 14:37:01 twilightnode nnrpd[3031953]: 198.235.24.98 no_match_user <anonymous> <localhost>
Jul 1 14:37:01 twilightnode nnrpd[3031953]: 198.235.24.98 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 1 14:37:01 twilightnode nnrpd[3031953]: 198.235.24.98 match_user <anonymous> <anonymous>
Jul 1 14:37:01 twilightnode nnrpd[3031953]: 198.235.24.98 no_post anonymous-read
Jul 1 15:51:46 twilightnode pgpverify[3035135]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 41187 84.0%5.6 MB 79.0%
inn 6538 13.3%1.2 MB 17.3%
innd 1235 2.5%249.8 KB 3.4%
nnrpd 55 0.1%6.1 KB 0.1%
controlchan 39 0.1%9.7 KB 0.1%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 6 49055 100.0%7.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15635 50.9%
Negative hits 8545 27.8%
Do not exist 6502 21.2%
Cache misses 7 0.0%
TOTAL: 4 30689 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.006 0.0%4 1.500 1.500 1.500
article cleanup00:00:00.061 0.0%6513 0.000 0.009 0.190
article logging00:00:00.253 0.0%6521 0.000 0.039 0.238
article parse00:00:00.155 0.0%9928 0.000 0.016 0.098
article write00:00:04.557 0.0%4757 0.450 0.958 2.619
data move00:00:00.132 0.0%35259 0.000 0.004 0.044
hisgrep/artcncl00:00:00.003 0.0%4 0.500 0.750 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.816 0.0%30735 0.075 0.157 0.885
history sync00:00:00.057 0.0%396 0.000 0.144 1.333
history write00:00:03.627 0.0%6513 0.138 0.557 2.207
idle23:50:22.001100.0%33793 1036.174 2539.638 11549.981
nntp read00:00:00.900 0.0%33486 0.000 0.027 0.118
overview write00:00:02.421 0.0%4757 0.171 0.509 3.222
perl filter00:00:11.307 0.0%6513 1.019 1.736 23.714
python filter00:00:00.960 0.0%6513 0.021 0.147 3.381
site send00:00:00.142 0.0%4760 0.000 0.030 0.158
TOTAL: 1723:50:51.398100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.035 0.0%10371 0.000 0.003 0.056
article prepare00:00:00.006 0.0%28216 0.000 0.000 0.008
article read00:00:00.766 0.0%4755 0.000 0.161 1.909
backlog stats00:00:00.061 0.0%70678 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.529 0.0%34751 0.000 0.015 0.076
data write00:00:00.891 0.0%30063 0.006 0.030 0.083
idle23:59:14.323100.0%70678 859.522 1221.799 1670.358
status file00:00:10.170 0.0%17024 0.450 0.597 0.880
TOTAL: 923:59:26.781100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:02.209100.0%6 74.500 368.167 697.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%26 0.000 0.038 0.200
TOTAL: 300:00:02.210100.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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
control@eternal-september.org001001
us-control@lists.killfile.org001101
TOTAL003103

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 10865 4014 5615 1236 36%47:35:37
2newsfeed.endofthelinebbs.com 1 4699 566 3658 475 12%22:29:36
3news.sklaffkom.se 45 142 109 31 2 76%12:55:52
4news.tebibyte.org 1 6228 52 6156 20 0%23:59:58
5localhost 25 0 0 0 0 0%00:00:01
6news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 6 77 21934 4741 15460 1733 21%107:11:04
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.6 MB0.0 KB4.7 MB18.3 MB 74%3.6 KB
2newsfeed.endofthelinebbs.com6.1 MB0.0 KB1.5 MB7.6 MB 80%7.5 KB
3news.sklaffkom.se721.1 KB0.0 KB5.7 KB726.8 KB 99%6.5 KB
4news.tebibyte.org291.2 KB0.0 KB86.3 KB377.5 KB 77%5.2 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: 620.7 MB0.0 KB6.3 MB27.0 MB 76%4.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 1 03:00:06 - 03:59:59 75 1.6% 0.02328.7 KB 1.5% 0.09
Jul 1 04:00:00 - 04:59:59 112 2.3% 0.03502.0 KB 2.3% 0.14
Jul 1 05:00:00 - 05:59:59 80 1.7% 0.02294.8 KB 1.4% 0.08
Jul 1 06:00:00 - 06:59:59 148 3.1% 0.04421.7 KB 1.9% 0.12
Jul 1 07:00:00 - 07:59:59 153 3.2% 0.04714.3 KB 3.3% 0.20
Jul 1 08:00:00 - 08:59:59 208 4.4% 0.061.8 MB 8.3% 0.51
Jul 1 09:00:00 - 09:59:59 206 4.3% 0.06918.1 KB 4.2% 0.26
Jul 1 10:00:00 - 10:59:59 196 4.1% 0.05604.3 KB 2.8% 0.17
Jul 1 11:00:00 - 11:59:59 233 4.9% 0.061.0 MB 4.9% 0.30
Jul 1 12:00:00 - 12:59:59 230 4.8% 0.06964.3 KB 4.4% 0.27
Jul 1 13:00:00 - 13:59:59 194 4.1% 0.05717.4 KB 3.3% 0.20
Jul 1 14:00:00 - 14:59:59 255 5.3% 0.071.1 MB 5.3% 0.32
Jul 1 15:00:00 - 15:59:59 292 6.1% 0.081.4 MB 6.7% 0.41
Jul 1 16:00:00 - 16:59:59 227 4.8% 0.06957.4 KB 4.4% 0.27
Jul 1 17:00:00 - 17:59:59 299 6.3% 0.081.4 MB 6.5% 0.40
Jul 1 18:00:00 - 18:59:59 236 4.9% 0.071.2 MB 5.7% 0.35
Jul 1 19:00:00 - 19:59:59 281 5.9% 0.081.1 MB 5.1% 0.31
Jul 1 20:00:00 - 20:59:59 253 5.3% 0.071.0 MB 4.7% 0.29
Jul 1 21:00:00 - 21:59:59 221 4.6% 0.06741.1 KB 3.4% 0.21
Jul 1 22:00:00 - 22:59:59 216 4.5% 0.061.3 MB 5.9% 0.36
Jul 1 23:00:00 - 23:59:59 238 5.0% 0.07990.1 KB 4.5% 0.28
Jul 2 00:00:00 - 00:59:59 175 3.7% 0.05966.8 KB 4.4% 0.27
Jul 2 01:00:00 - 01:59:59 126 2.6% 0.04713.9 KB 3.3% 0.20
Jul 2 02:00:00 - 02:59:59 103 2.2% 0.03346.9 KB 1.6% 0.10
Jul 2 03:00:00 - 03:00:05 11 0.2% 2.2021.3 KB 0.1% 4.26
TOTAL: 23:59:59 4768 100.0% 0.0621.3 MB 100.0% 0.25
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1256 1196 0 0 0 0 0 0 60
2newsfeed.endofthelinebbs.com 481 87 0 0 0 0 0 0 394
3news.tebibyte.org 31 29 0 0 0 0 0 0 2
4news.sklaffkom.se 2 2 0 0 0 0 0 0 0
TOTAL: 4 1770 1314 0 0 0 0 0 0 456

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 46
it.hobby.cucina 38
de.soc.recht.misc 34
de.talk.jokes 33
alt.checkmate 29
it.discussioni.auto 29
alt.recovery.aa 28
linux.debian.maint.ocaml.maint 28
uk.net.news.moderation 28
pl.soc.prawo 26
it.cultura.linguistica.italiano 25
uk.legal.moderated 25
linux.debian.devel 22
cn.comp 20
de.alt.talk.unmut 19
uk.religion.christian 19
linux.debian.user 18
alt.bestjobsusa.computer.jobs 16
lada.talk 16
alt.food.fast-food 15
TOTAL: 293 1314

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 396
User-issued cancel 43
Too many newsgroups 6
HTML Multipart 3
TOTAL: 4 448

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4769 4755 0 13 0 0 99%23:59:53
2sklaffkom.se 5078 741 2452 1562 0 2 14%23:59:53
3news.tebibyte.org 2837 0 0 0 0 7591 0%23:59:53
4newsfeed.endofthelinebbs.com 2777 0 0 0 0 6951 0%23:59:53
5weretis.net 791 0 742 0 0 0 0%22:54:41
6sklaff.dev 0 0 0 0 0 4754 0%23:57:02
7thebunker.nu 0 0 0 0 0 4768 0%23:59:53
8usenet.blueworldhosting.com 0 0 0 0 0 4367 0%23:59:53
TOTAL: 8 16252 5496 3194 1575 0 28433 33%190:51:01
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se21.3 MB22.3 KB21.3 MB0.3 KB/s4.6 KB23:59:53
2sklaffkom.se1.3 MB10.0 MB11.3 MB0.1 KB/s5.0 KB23:59:53
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:02
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:54:41
TOTAL: 822.6 MB10.0 MB32.6 MB0.0 KB/s4.7 KB190:51:01
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1105.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
2195.140.214.20 1 00.0 KB 0 0 000:00:00
3198.235.24.98 1 00.0 KB 0 0 000:00:00
4213.166.84.58 1 00.0 KB 0 0 000:00:00
TOTAL: 4 4 00.0 KB 0 0 000:00:02

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 0 0 000:00:00
2*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
TOTAL: 2 4 00.0 KB 0 0 000:00:02

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
105.172.132.66.censys-scanner.com 0.026 0.026 0.00000:00:01
198.235.24.98 0.005 0.019 0.00000:00:00
195.140.214.20 0.001 0.017 0.00000:00:00
213.166.84.58 0.002 0.009 0.00000:00:00
TOTAL: 4 0.034 0.071 0.00000:00:02

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

SystemConn
105.172.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
213.166.84.58 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