Daily Usenet report

May 18 03:00:06 -- May 19 03:00:04

Unknown entries from news log file:

First 50 / 84 lines (59.5%)

May 18 03:01:43 mail innd: message repeated 2 times: [ ctlinnd command s]
May 18 03:33:12 mail nnrpd[15652]: Reading access from /usr/local/news/etc/readers.conf
May 18 03:33:12 mail nnrpd[15652]: Auth strategy 'localhost' does not match client. Removing.
May 18 03:33:12 mail nnrpd[15652]: 89.37.172.148 no_success_auth
May 18 03:33:16 mail nnrpd[15654]: Reading access from /usr/local/news/etc/readers.conf
May 18 03:33:16 mail nnrpd[15654]: Auth strategy 'localhost' does not match client. Removing.
May 18 03:33:16 mail nnrpd[15654]: 31.14.254.104 no_success_auth
May 18 06:10:26 mail nnrpd[23119]: Reading access from /usr/local/news/etc/readers.conf
May 18 06:10:26 mail nnrpd[23119]: Auth strategy 'localhost' does not match client. Removing.
May 18 06:10:26 mail nnrpd[23119]: 205.210.31.6 no_success_auth
May 18 13:37:57 mail nnrpd[43625]: Reading access from /usr/local/news/etc/readers.conf
May 18 13:37:57 mail nnrpd[43625]: Auth strategy 'localhost' does not match client. Removing.
May 18 13:37:57 mail nnrpd[43625]: 172-236-228-38.ip.linodeusercontent.com no_success_auth
May 18 13:37:57 mail nnrpd[43626]: Reading access from /usr/local/news/etc/readers.conf
May 18 13:37:57 mail nnrpd[43626]: Auth strategy 'localhost' does not match client. Removing.
May 18 13:37:57 mail nnrpd[43626]: 172-236-228-38.ip.linodeusercontent.com no_success_auth
May 18 20:22:14 mail nnrpd[62666]: Reading access from /usr/local/news/etc/readers.conf
May 18 20:22:14 mail nnrpd[62666]: Auth strategy 'localhost' does not match client. Removing.
May 18 20:22:14 mail nnrpd[62666]: 71.6.134.231 no_success_auth
May 18 20:45:54 mail nnrpd[64098]: Reading access from /usr/local/news/etc/readers.conf
May 18 20:45:54 mail nnrpd[64098]: Auth strategy 'localhost' does not match client. Removing.
May 18 20:45:54 mail nnrpd[64098]: azpdesm9j3w4.stretchoid.com no_success_auth
May 18 20:46:04 mail nnrpd[64111]: Reading access from /usr/local/news/etc/readers.conf
May 18 20:46:04 mail nnrpd[64111]: Auth strategy 'localhost' does not match client. Removing.
May 18 20:46:04 mail nnrpd[64111]: azpdesm9j3w4.stretchoid.com no_success_auth
May 18 22:17:05 mail nnrpd[69885]: Reading access from /usr/local/news/etc/readers.conf
May 18 22:17:05 mail nnrpd[69885]: mail.twilightnode.org res <localhost>
May 18 22:17:05 mail nnrpd[69885]: mail.twilightnode.org match_user <localhost> <localhost>
May 18 22:43:59 mail nnrpd[72824]: Reading access from /usr/local/news/etc/readers.conf
May 18 22:43:59 mail nnrpd[72824]: localhost res <localhost>
May 18 22:43:59 mail nnrpd[72824]: localhost match_user <localhost> <localhost>
May 18 22:44:22 mail nnrpd[72843]: Reading access from /usr/local/news/etc/readers.conf
May 18 22:44:22 mail nnrpd[72843]: localhost res <localhost>
May 18 22:44:23 mail nnrpd[72843]: localhost match_user <localhost> <localhost>
May 18 22:49:30 mail nnrpd[73276]: Reading access from /usr/local/news/etc/readers.conf
May 18 22:49:30 mail nnrpd[73276]: mail.twilightnode.org res <localhost>
May 18 22:49:30 mail nnrpd[73276]: mail.twilightnode.org match_user <localhost> <localhost>
May 18 23:18:06 mail nnrpd[75287]: Reading access from /usr/local/news/etc/readers.conf
May 18 23:18:06 mail nnrpd[75287]: mail.twilightnode.org res <localhost>
May 18 23:18:06 mail nnrpd[75287]: mail.twilightnode.org match_user <localhost> <localhost>
May 18 23:32:59 mail nnrpd[76638]: Reading access from /usr/local/news/etc/readers.conf
May 18 23:32:59 mail nnrpd[76638]: twilightnode.org res <localhost>
May 18 23:32:59 mail nnrpd[76638]: twilightnode.org match_user <localhost> <localhost>
May 18 23:37:18 mail nnrpd[76835]: Reading access from /usr/local/news/etc/readers.conf
May 18 23:37:18 mail nnrpd[76835]: twilightnode.org res <localhost>
May 18 23:37:18 mail nnrpd[76835]: twilightnode.org match_user <localhost> <localhost>
May 18 23:47:09 mail nnrpd[77394]: Reading access from /usr/local/news/etc/readers.conf
May 18 23:47:09 mail nnrpd[77394]: twilightnode.org res <localhost>
May 18 23:47:09 mail nnrpd[77394]: twilightnode.org match_user <localhost> <localhost>
May 18 23:47:29 mail nnrpd[77402]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
inn 8014 64.0%1.1 MB 67.3%
innd 2952 23.6%344.6 KB 21.2%
innfeed 1321 10.6%164.5 KB 10.1%
nnrpd 223 1.8%21.4 KB 1.3%
controlchan 4 0.0%0.2 KB 0.0%
TOTAL: 5 12514 100.0%1.6 MB100.0%

History cache:

ReasonCount%Count
Negative hits 8581 35.0%
Do not exist 7976 32.5%
Positive hits 7874 32.1%
Cache misses 81 0.3%
TOTAL: 4 24512 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%0 0.000 0.000 0.000
article cleanup00:00:00.085 0.0%7988 0.000 0.011 0.312
article logging00:00:00.399 0.0%7988 0.000 0.050 0.296
article parse00:00:00.114 0.0%10551 0.000 0.011 0.077
article write00:00:01.466 0.0%1503 0.333 0.975 4.400
data move00:00:00.066 0.0%21017 0.000 0.003 0.053
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.134 0.0%24561 0.078 0.209 0.654
history sync00:00:00.081 0.0%416 0.000 0.195 1.000
history write00:00:04.549 0.0%7988 0.250 0.569 1.176
idle23:50:28.764 99.9%21254 529.927 4038.241 22247.185
nntp read00:00:00.537 0.0%20874 0.000 0.026 0.225
overview write00:00:01.026 0.0%1503 0.133 0.683 2.000
perl filter00:00:18.244 0.0%7988 0.624 2.284 14.150
python filter00:00:01.373 0.0%7996 0.022 0.172 0.926
site send00:00:00.083 0.0%1503 0.000 0.055 0.667
TOTAL: 1623:51:01.921100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.017 0.0%1497 0.000 0.011 0.250
article prepare00:00:00.000 0.0%11992 0.000 0.000 0.000
article read00:00:00.455 0.0%1497 0.000 0.304 1.200
backlog stats00:00:00.018 0.0%13642 0.000 0.001 0.018
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.130 0.0%5757 0.000 0.023 0.167
data write00:00:00.163 0.0%4321 0.000 0.038 0.378
idle23:50:45.427100.0%13642 2846.687 6292.730 12827.128
status file00:00:00.260 0.0%212 0.000 1.226 27.000
TOTAL: 923:50:46.470100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:11:30.850100.0%151 2.000 4575.166300417.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.181 0.0%16664 0.000 0.011 1.000
readart00:00:00.044 0.0%80 0.550 0.550 0.550
TOTAL: 400:11:31.075100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 148
name 2
pause 2
paused 2
reload 3
reserve 2
xexec 3
TOTAL: 12 172

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 8 15762 1567 7729 6466 9%48:36:15
2localhost 35 8 8 0 0 100%00:00:01
3news.chimia.se 1 0 0 0 0 0%00:10:00
4news.twilightnode.org 12 0 0 0 0 0%00:00:00
TOTAL: 4 56 15770 1575 7729 6466 9%48:46:16
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net6.6 MB0.0 KB17.3 MB23.9 MB 27%3.0 KB
2localhost5.8 KB0.0 KB0.0 KB5.8 KB100%0.7 KB
3news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
4news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 46.6 MB0.0 KB17.3 MB23.9 MB 27%3.0 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 18 03:00:06 - 03:59:59 49 3.2% 0.01391.3 KB 6.1% 0.11
May 18 04:00:00 - 04:59:59 71 4.7% 0.02544.7 KB 8.5% 0.15
May 18 05:00:00 - 05:59:59 37 2.4% 0.01349.5 KB 5.5% 0.10
May 18 06:00:00 - 06:59:59 52 3.4% 0.01114.8 KB 1.8% 0.03
May 18 07:00:00 - 07:59:59 77 5.1% 0.02512.7 KB 8.0% 0.14
May 18 08:00:00 - 08:59:59 64 4.2% 0.02445.4 KB 7.0% 0.12
May 18 09:00:00 - 09:59:59 57 3.8% 0.02237.6 KB 3.7% 0.07
May 18 10:00:00 - 10:59:59 45 3.0% 0.0197.1 KB 1.5% 0.03
May 18 11:00:00 - 11:59:59 46 3.0% 0.0194.3 KB 1.5% 0.03
May 18 12:00:00 - 12:59:59 60 4.0% 0.02126.1 KB 2.0% 0.04
May 18 13:00:00 - 13:59:59 47 3.1% 0.0183.4 KB 1.3% 0.02
May 18 14:00:00 - 14:59:59 58 3.8% 0.02154.0 KB 2.4% 0.04
May 18 15:00:00 - 15:59:59 67 4.4% 0.02131.9 KB 2.1% 0.04
May 18 16:00:00 - 16:59:59 63 4.2% 0.02283.9 KB 4.4% 0.08
May 18 17:00:00 - 17:59:59 82 5.4% 0.02509.1 KB 8.0% 0.14
May 18 18:00:00 - 18:59:59 77 5.1% 0.02405.8 KB 6.3% 0.11
May 18 19:00:00 - 19:59:59 85 5.6% 0.02206.4 KB 3.2% 0.06
May 18 20:00:00 - 20:59:59 79 5.2% 0.02193.0 KB 3.0% 0.05
May 18 21:00:00 - 21:59:59 75 5.0% 0.02130.6 KB 2.0% 0.04
May 18 22:00:00 - 22:59:59 84 5.6% 0.02275.6 KB 4.3% 0.08
May 18 23:00:00 - 23:59:59 77 5.1% 0.02165.9 KB 2.6% 0.05
May 19 00:00:00 - 00:59:59 53 3.5% 0.01410.1 KB 6.4% 0.11
May 19 01:00:00 - 01:59:59 45 3.0% 0.01398.4 KB 6.2% 0.11
May 19 02:00:00 - 02:59:59 54 3.6% 0.01131.7 KB 2.1% 0.04
May 19 03:00:00 - 03:00:04 9 0.6% 2.257.2 KB 0.1% 1.80
TOTAL: 23:59:58 1513 100.0% 0.026.3 MB 100.0% 0.07
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 6501 4601 0 0 0 0 0 0 1900
TOTAL: 1 6501 4601 0 0 0 0 0 0 1900

Unwanted newsgroups [Top 20]:

NewsgroupCount
free.usenet 1213
fido7.ru.news 217
fido7.su.pol 215
linux.debian.maint.ocaml.maint 169
free.pt 157
linux.debian.changes.devel 135
linux.debian.bugs.dist 112
fr.soc.politique 108
de.soc.politik.misc 105
alt.buddha.short.fat.guy 89
uk.legal.moderated 89
it.hobby.cucina 85
linux.debian.bugs.rc 81
pl.soc.prawo 68
alt.usage.english 61
de.talk.tagesgeschehen 61
it-alt.sport.calcio.juventus 58
ger.ct 56
it.sport.calcio.milan 50
it.politica 43
TOTAL: 259 4601

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 1675
EMP (phl) 151
User-issued cancel 47
EMP (md5) 13
Too many newsgroups 6
Too many newsgroups (meow) 4
EMP (phr path) 2
HTML Multipart 2
TOTAL: 8 1900

Miscellaneous innd events:

EventsCount
CCreader 1
TOTAL: 1 1

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 1516 1514 0 0 0 0 99%23:59:45
2thebunker.nu 1513 1389 0 120 0 7 91%23:59:45
3weretis.net 8 7 0 1 0 0 87%01:18:02
4sklaff.dev 0 0 0 0 0 1507 0%23:53:01
5usenet.blueworldhosting.com 0 0 0 0 0 1329 0%23:59:44
TOTAL: 5 3037 2910 0 121 0 2843 95%97:10:17
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se6.3 MB0.0 KB6.3 MB0.1 KB/s4.2 KB23:59:45
2thebunker.nu5.9 MB383.7 KB6.3 MB0.1 KB/s4.2 KB23:59:45
3weretis.net5.0 KB0.8 KB5.8 KB0.0 KB/s0.7 KB01:18:02
4sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:53:01
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
TOTAL: 512.1 MB384.6 KB12.5 MB0.0 KB/s4.2 KB97:10:17
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1localhost 2 8078.1 KB 1 0 000:01:10
2twilightnode.org 9 00.0 KB 0 5 000:00:00
3mail.twilightnode.org 3 00.0 KB 0 3 000:00:00
TOTAL: 3 14 8078.1 KB 1 8 000:11:11

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

SystemConnArtsSizeGroupsPostRejElapsed
1*localhost 2 8078.1 KB 1 0 000:01:10
2*.org 9 00.0 KB 0 5 000:00:00
3unresolved 7 00.0 KB 0 0 000:00:06
4*.twilightnode.org 3 00.0 KB 0 3 000:00:00
5*.ip.linodeusercontent.com 2 00.0 KB 0 0 000:00:00
6*.stretchoid.com 2 00.0 KB 0 0 000:00:10
7*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
8*.contaboserver.net 1 00.0 KB 0 0 000:00:00
TOTAL: 8 27 8078.1 KB 1 8 000:11:31

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195-252-55-9-no600.tbcn.telia.com 0.005 0.017 0.00000:10:00
localhost 0.059 0.304 0.00000:01:10
azpdesm9j3w4.stretchoid.com 0.009 0.024 0.00000:00:10
31.14.254.101 0.004 0.011 0.00000:00:04
48.195.132.66.censys-scanner.com 0.042 0.028 0.00000:00:01
172-236-228-38.ip.linodeusercontent.com 0.007 0.018 0.00000:00:00
69.5.169.146 0.006 0.015 0.00000:00:00
71.6.134.231 0.000 0.012 0.00000:00:00
89.37.172.148 0.002 0.019 0.00000:00:00
vmd129036.contaboserver.net 0.003 0.023 0.00000:00:00
205.210.31.6 0.008 0.017 0.00000:00:00
31.14.254.104 0.002 0.009 0.00000:00:00
twilightnode.org 0.050 0.094 0.00000:00:00
89.21.67.165 0.002 0.013 0.00000:00:00
mail.twilightnode.org 0.018 0.060 0.00000:00:00
TOTAL: 15 0.217 0.664 0.00000:11:31

Curious NNRP explorers [Top 20]:

SystemConn
172-236-228-38.ip.linodeusercontent.com 2
azpdesm9j3w4.stretchoid.com 2
205.210.31.6 1
31.14.254.101 1
31.14.254.104 1
48.195.132.66.censys-scanner.com 1
69.5.169.146 1
71.6.134.231 1
89.21.67.165 1
89.37.172.148 1
vmd129036.contaboserver.net 1
TOTAL: 11 13

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

SystemConn
172-236-228-38.ip.linodeusercontent.com 7
48.195.132.66.censys-scanner.com 1
azpdesm9j3w4.stretchoid.com 1
TOTAL: 3 9

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

CommandCount
#001 1
#026#003#001 1
Accept-Encoding: gzip 1
Accept: */* 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
TOTAL: 9 9

NNRP client timeouts [Top 20]:

SystemConnPeer
31.14.254.101 1 1
31.14.254.104 1 1
TOTAL: 2 2 6

Newsgroup request counts (by hierarchy) [Top 100]:

HierarchyCountPct
1alt 80100.0%
TOTAL: 1 80100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1alt.test 80
TOTAL: 1 80
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