Daily Usenet report

Jul 21 03:00:05 -- Jul 22 03:00:05

Unknown entries from news log file:

First 50 / 91 lines (54.9%)

Jul 21 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 21 03:23:22 twilightnode nnrpd[347795]: Reading access from /usr/local/news/etc/readers.conf
Jul 21 03:23:22 twilightnode nnrpd[347795]: Auth strategy 'localhost' does not match client. Removing.
Jul 21 03:23:22 twilightnode nnrpd[347795]: o013.scanner.modat.io res <anonymous>
Jul 21 03:23:22 twilightnode nnrpd[347795]: o013.scanner.modat.io no_match_user <anonymous> <localhost>
Jul 21 03:23:22 twilightnode nnrpd[347795]: o013.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 21 03:23:22 twilightnode nnrpd[347795]: o013.scanner.modat.io match_user <anonymous> <anonymous>
Jul 21 03:23:22 twilightnode nnrpd[347795]: o013.scanner.modat.io no_post anonymous-read
Jul 21 07:48:12 twilightnode nnrpd[366412]: Reading access from /usr/local/news/etc/readers.conf
Jul 21 07:48:12 twilightnode nnrpd[366412]: Auth strategy 'localhost' does not match client. Removing.
Jul 21 07:48:12 twilightnode nnrpd[366412]: 69.5.169.13 res <anonymous>
Jul 21 07:48:12 twilightnode nnrpd[366412]: 69.5.169.13 no_match_user <anonymous> <localhost>
Jul 21 07:48:12 twilightnode nnrpd[366412]: 69.5.169.13 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 21 07:48:12 twilightnode nnrpd[366412]: 69.5.169.13 match_user <anonymous> <anonymous>
Jul 21 07:48:12 twilightnode nnrpd[366412]: 69.5.169.13 no_post anonymous-read
Jul 21 07:48:20 twilightnode nnrpd[366413]: Reading access from /usr/local/news/etc/readers.conf
Jul 21 07:48:20 twilightnode nnrpd[366413]: Auth strategy 'localhost' does not match client. Removing.
Jul 21 07:48:20 twilightnode nnrpd[366413]: 213.166.84.52 res <anonymous>
Jul 21 07:48:20 twilightnode nnrpd[366413]: 213.166.84.52 no_match_user <anonymous> <localhost>
Jul 21 07:48:20 twilightnode nnrpd[366413]: 213.166.84.52 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 21 07:48:20 twilightnode nnrpd[366413]: 213.166.84.52 match_user <anonymous> <anonymous>
Jul 21 07:48:20 twilightnode nnrpd[366413]: 213.166.84.52 no_post anonymous-read
Jul 21 08:55:44 twilightnode nnrpd[369307]: Reading access from /usr/local/news/etc/readers.conf
Jul 21 08:55:44 twilightnode nnrpd[369307]: Auth strategy 'localhost' does not match client. Removing.
Jul 21 08:55:44 twilightnode nnrpd[369307]: 227.224.132.66.censys-scanner.com res <anonymous>
Jul 21 08:55:44 twilightnode nnrpd[369307]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 21 08:55:44 twilightnode nnrpd[369307]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 21 08:55:44 twilightnode nnrpd[369307]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 21 08:55:44 twilightnode nnrpd[369307]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Jul 21 08:55:51 twilightnode nnrpd[369308]: Reading access from /usr/local/news/etc/readers.conf
Jul 21 08:55:51 twilightnode nnrpd[369308]: Auth strategy 'localhost' does not match client. Removing.
Jul 21 08:55:51 twilightnode nnrpd[369308]: 227.224.132.66.censys-scanner.com res <anonymous>
Jul 21 08:55:51 twilightnode nnrpd[369308]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 21 08:55:51 twilightnode nnrpd[369308]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 21 08:55:51 twilightnode nnrpd[369308]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 21 08:55:51 twilightnode nnrpd[369308]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Jul 21 08:55:54 twilightnode nnrpd[369309]: Reading access from /usr/local/news/etc/readers.conf
Jul 21 08:55:54 twilightnode nnrpd[369309]: Auth strategy 'localhost' does not match client. Removing.
Jul 21 08:55:54 twilightnode nnrpd[369309]: 227.224.132.66.censys-scanner.com res <anonymous>
Jul 21 08:55:54 twilightnode nnrpd[369309]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 21 08:55:54 twilightnode nnrpd[369309]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 21 08:55:54 twilightnode nnrpd[369309]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 21 08:55:54 twilightnode nnrpd[369309]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Jul 21 08:55:55 twilightnode nnrpd[369310]: Reading access from /usr/local/news/etc/readers.conf
Jul 21 08:55:55 twilightnode nnrpd[369310]: Auth strategy 'localhost' does not match client. Removing.
Jul 21 08:55:55 twilightnode nnrpd[369310]: 227.224.132.66.censys-scanner.com res <anonymous>
Jul 21 08:55:55 twilightnode nnrpd[369310]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 21 08:55:55 twilightnode nnrpd[369310]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 21 08:55:55 twilightnode nnrpd[369310]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 21 08:55:55 twilightnode nnrpd[369310]: 227.224.132.66.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6311 71.4%964.0 KB 71.7%
innfeed 1191 13.5%182.0 KB 13.5%
innd 1181 13.4%179.7 KB 13.4%
nnrpd 143 1.6%14.9 KB 1.1%
controlchan 14 0.2%3.2 KB 0.2%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 6 8841 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13739 47.7%
Negative hits 8830 30.6%
Do not exist 6256 21.7%
Cache misses 0 0.0%
TOTAL: 4 28825 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.007 0.0%3 1.000 2.333 3.000
article cleanup00:00:00.055 0.0%6280 0.000 0.009 0.100
article logging00:00:00.295 0.0%6314 0.000 0.047 0.200
article parse00:00:00.101 0.0%8305 0.000 0.012 0.059
article write00:00:04.983 0.0%4780 0.393 1.042 4.089
data move00:00:00.104 0.0%31470 0.000 0.003 0.023
hisgrep/artcncl00:00:00.003 0.0%3 0.000 1.000 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.067 0.0%28928 0.089 0.175 0.393
history sync00:00:00.065 0.0%412 0.000 0.158 1.000
history write00:00:03.558 0.0%6280 0.200 0.567 1.447
idle24:00:30.476100.0%30917 1612.847 2795.565 18209.455
nntp read00:00:00.864 0.0%30560 0.000 0.028 0.181
overview write00:00:02.785 0.0%4780 0.222 0.583 3.188
perl filter00:00:10.133 0.0%6280 0.949 1.614 16.909
python filter00:00:00.757 0.0%6280 0.000 0.121 2.000
site send00:00:00.169 0.0%4768 0.000 0.035 0.188
TOTAL: 1724:00:59.422100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.009 0.0%4829 0.000 0.002 0.091
article prepare00:00:00.002 0.0%13716 0.000 0.000 0.008
article read00:00:00.694 0.0%2621 0.000 0.265 2.333
backlog stats00:00:00.024 0.0%51489 0.000 0.000 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.477 0.0%27963 0.000 0.017 0.110
data write00:00:00.762 0.0%23047 0.000 0.033 0.092
idle23:58:16.232100.0%51489 486.007 1676.013 4921.803
status file00:00:00.189 0.0%208 0.000 0.909 6.000
TOTAL: 923:58:18.389100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:23.742100.0%17 90.000 1396.588 10273.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%91 0.000 0.022 0.059
TOTAL: 300:00:23.744100.0%----

Control commands to innd:

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

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
nl-admin@nic.surfnet.nl001101
TOTAL001101

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 9886 3900 5048 938 39%43:27:36
2newsfeed.endofthelinebbs.com 1 5152 527 4122 503 10%27:30:26
3news.tebibyte.org 2 5129 132 4954 43 2%23:57:50
4news.sklaffkom.se 42 86 21 44 21 24%09:43:37
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 73 20253 4580 14168 1505 22%104:39:29
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.7 MB0.0 KB2.5 MB14.2 MB 82%3.0 KB
2newsfeed.endofthelinebbs.com5.1 MB0.0 KB1.5 MB6.5 MB 77%6.5 KB
3news.tebibyte.org535.2 KB0.0 KB127.5 KB662.7 KB 80%3.8 KB
4news.sklaffkom.se52.6 KB0.0 KB49.7 KB102.3 KB 51%2.4 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 517.4 MB0.0 KB4.1 MB21.5 MB 80%3.6 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 21 03:00:05 - 03:59:59 192 4.0% 0.05868.9 KB 4.9% 0.24
Jul 21 04:00:00 - 04:59:59 157 3.3% 0.04840.4 KB 4.7% 0.23
Jul 21 05:00:00 - 05:59:59 89 1.9% 0.02305.6 KB 1.7% 0.08
Jul 21 06:00:00 - 06:59:59 112 2.3% 0.03352.2 KB 2.0% 0.10
Jul 21 07:00:00 - 07:59:59 127 2.7% 0.04401.9 KB 2.2% 0.11
Jul 21 08:00:00 - 08:59:59 175 3.7% 0.05492.6 KB 2.8% 0.14
Jul 21 09:00:00 - 09:59:59 261 5.5% 0.07755.1 KB 4.2% 0.21
Jul 21 10:00:00 - 10:59:59 264 5.5% 0.07927.2 KB 5.2% 0.26
Jul 21 11:00:00 - 11:59:59 226 4.7% 0.06719.9 KB 4.0% 0.20
Jul 21 12:00:00 - 12:59:59 214 4.5% 0.06792.1 KB 4.4% 0.22
Jul 21 13:00:00 - 13:59:59 208 4.4% 0.06900.1 KB 5.0% 0.25
Jul 21 14:00:00 - 14:59:59 235 4.9% 0.07650.9 KB 3.6% 0.18
Jul 21 15:00:00 - 15:59:59 188 3.9% 0.05734.7 KB 4.1% 0.20
Jul 21 16:00:00 - 16:59:59 269 5.6% 0.07738.6 KB 4.1% 0.21
Jul 21 17:00:00 - 17:59:59 256 5.4% 0.07858.1 KB 4.8% 0.24
Jul 21 18:00:00 - 18:59:59 237 5.0% 0.07746.8 KB 4.2% 0.21
Jul 21 19:00:00 - 19:59:59 247 5.2% 0.071.0 MB 5.8% 0.29
Jul 21 20:00:00 - 20:59:59 230 4.8% 0.06937.3 KB 5.2% 0.26
Jul 21 21:00:00 - 21:59:59 238 5.0% 0.07827.4 KB 4.6% 0.23
Jul 21 22:00:00 - 22:59:59 222 4.6% 0.06904.6 KB 5.1% 0.25
Jul 21 23:00:00 - 23:59:59 204 4.3% 0.06822.1 KB 4.6% 0.23
Jul 22 00:00:00 - 00:59:59 159 3.3% 0.04938.7 KB 5.3% 0.26
Jul 22 01:00:00 - 01:59:59 148 3.1% 0.04581.2 KB 3.3% 0.16
Jul 22 02:00:00 - 02:59:59 109 2.3% 0.03727.6 KB 4.1% 0.20
Jul 22 03:00:00 - 03:00:05 9 0.2% 1.808.1 KB 0.0% 1.63
TOTAL: 24:00:00 4776 100.0% 0.0617.4 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1046 970 0 0 0 0 0 0 76
2newsfeed.endofthelinebbs.com 443 74 0 0 0 0 0 0 369
3news.tebibyte.org 25 23 0 0 0 0 0 0 2
4news.sklaffkom.se 21 21 0 0 0 0 0 0 0
TOTAL: 4 1535 1088 0 0 0 0 0 0 447

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 46
uk.legal.moderated 29
alt.checkmate 28
lada.talk 28
it-alt.sport.calcio.juventus 27
pl.misc.elektronika 26
alt.prophecies.nostradamus 25
it.comp.hardware 25
hfx.general 23
de.soc.recht.misc 21
alt.slack 20
cn.comp 20
pl.soc.polityka 20
uk.net.news.moderation 20
muc.lists.freebsd.bugs 19
it.comp.sicurezza.varie 18
fido7.ukrnews 17
muc.lists.freebsd.hackers 17
alt.fan.rush-limbaurgh 16
pl.misc.samochody 16
TOTAL: 215 1088

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 378
User-issued cancel 24
Too many newsgroups 6
HTML Multipart 5
TOTAL: 4 413

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 5104 907 2188 1666 0 3 17%23:59:37
2news.tebibyte.org 6654 831 3930 3 0 12 12%23:59:30
3newsfeed.endofthelinebbs.com 7822 15 4300 6 0 60 0%23:59:54
4weretis.net 641 4 625 0 0 0 0%22:41:27
TOTAL: 4 20221 1757 11043 1675 0 75 8%94:40:28
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.4 MB301.0 KB2.7 MB0.0 KB/s3.3 KB23:59:30
2sklaffkom.se1.5 MB9.2 MB10.6 MB0.1 KB/s4.2 KB23:59:37
3newsfeed.endofthelinebbs.com38.8 KB11.0 KB49.8 KB0.0 KB/s2.4 KB23:59:54
4weretis.net16.5 KB0.0 KB16.5 KB0.0 KB/s4.1 KB22:41:27
TOTAL: 43.9 MB9.5 MB13.4 MB0.0 KB/s4.0 KB94:40:28
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1227.224.132.66.censys-scanner.com 4 00.0 KB 0 0 000:00:07
2azpdsgas24j5.stretchoid.com 2 00.0 KB 0 0 000:00:10
3111.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
4213.166.84.52 1 00.0 KB 0 0 000:00:00
5216.226.76.10 1 00.0 KB 0 0 000:00:00
669.5.169.13 1 00.0 KB 0 0 000:00:00
7o013.scanner.modat.io 1 00.0 KB 0 0 000:00:00
8r4-48-30.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 8 12 00.0 KB 0 0 000:00:23

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 5 00.0 KB 0 0 000:00:10
2unresolved 3 00.0 KB 0 0 000:00:00
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 5 12 00.0 KB 0 0 000:00:23

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdsgas24j5.stretchoid.com 0.009 0.021 0.00000:00:10
227.224.132.66.censys-scanner.com 0.015 0.030 0.00000:00:07
111.195.132.66.censys-scanner.com 0.030 0.017 0.00000:00:03
r4-48-30.monitoring.internet-measurement.com 0.003 0.025 0.00000:00:01
216.226.76.10 0.003 0.011 0.00000:00:00
213.166.84.52 0.005 0.009 0.00000:00:00
o013.scanner.modat.io 0.004 0.014 0.00000:00:00
69.5.169.13 0.005 0.017 0.00000:00:00
TOTAL: 8 0.074 0.144 0.00000:00:23

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

SystemConn
111.195.132.66.censys-scanner.com 1
azpdsgas24j5.stretchoid.com 1
TOTAL: 2 2

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
213.166.84.52 1 1
227.224.132.66.censys-scanner.com 1 4
TOTAL: 2 2 6
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