Daily Usenet report

Jun 21 03:00:05 -- Jun 22 03:00:05

Unknown entries from news log file:

First 48 / 48 lines (100.0%)

Jun 21 03:01:19 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 21 08:17:31 twilightnode nnrpd[2359860]: Reading access from /usr/local/news/etc/readers.conf
Jun 21 08:17:31 twilightnode nnrpd[2359860]: Auth strategy 'localhost' does not match client. Removing.
Jun 21 08:17:31 twilightnode nnrpd[2359860]: 69.5.169.162 res <anonymous>
Jun 21 08:17:31 twilightnode nnrpd[2359860]: 69.5.169.162 no_match_user <anonymous> <localhost>
Jun 21 08:17:31 twilightnode nnrpd[2359860]: 69.5.169.162 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 21 08:17:31 twilightnode nnrpd[2359860]: 69.5.169.162 match_user <anonymous> <anonymous>
Jun 21 08:17:31 twilightnode nnrpd[2359860]: 69.5.169.162 no_post anonymous-read
Jun 21 08:19:30 twilightnode nnrpd[2359876]: Reading access from /usr/local/news/etc/readers.conf
Jun 21 08:19:30 twilightnode nnrpd[2359876]: Auth strategy 'localhost' does not match client. Removing.
Jun 21 08:19:30 twilightnode nnrpd[2359876]: 69.5.169.2 res <anonymous>
Jun 21 08:19:30 twilightnode nnrpd[2359876]: 69.5.169.2 no_match_user <anonymous> <localhost>
Jun 21 08:19:30 twilightnode nnrpd[2359876]: 69.5.169.2 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 21 08:19:30 twilightnode nnrpd[2359876]: 69.5.169.2 match_user <anonymous> <anonymous>
Jun 21 08:19:30 twilightnode nnrpd[2359876]: 69.5.169.2 no_post anonymous-read
Jun 21 11:32:57 twilightnode nnrpd[2368114]: Reading access from /usr/local/news/etc/readers.conf
Jun 21 11:32:57 twilightnode nnrpd[2368114]: Auth strategy 'localhost' does not match client. Removing.
Jun 21 11:32:57 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com res <anonymous>
Jun 21 11:32:57 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 21 11:32:57 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 21 11:32:57 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 21 11:32:57 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com no_post anonymous-read
Jun 21 11:32:58 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com res <anonymous>
Jun 21 11:32:58 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 21 11:32:58 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 21 11:32:58 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 21 11:32:58 twilightnode nnrpd[2368114]: 231.224.132.66.censys-scanner.com no_post anonymous-read
Jun 21 14:25:06 twilightnode nnrpd[2375539]: Reading access from /usr/local/news/etc/readers.conf
Jun 21 14:25:06 twilightnode nnrpd[2375539]: Auth strategy 'localhost' does not match client. Removing.
Jun 21 14:25:06 twilightnode nnrpd[2375539]: r4-141-8d.monitoring.internet-measurement.com res <anonymous>
Jun 21 14:25:06 twilightnode nnrpd[2375539]: r4-141-8d.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jun 21 14:25:06 twilightnode nnrpd[2375539]: r4-141-8d.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 21 14:25:06 twilightnode nnrpd[2375539]: r4-141-8d.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jun 21 14:25:06 twilightnode nnrpd[2375539]: r4-141-8d.monitoring.internet-measurement.com no_post anonymous-read
Jun 21 20:16:08 twilightnode nnrpd[2391269]: Reading access from /usr/local/news/etc/readers.conf
Jun 21 20:16:08 twilightnode nnrpd[2391269]: Auth strategy 'localhost' does not match client. Removing.
Jun 21 20:16:08 twilightnode nnrpd[2391269]: o346.scanner.modat.io res <anonymous>
Jun 21 20:16:08 twilightnode nnrpd[2391269]: o346.scanner.modat.io no_match_user <anonymous> <localhost>
Jun 21 20:16:08 twilightnode nnrpd[2391269]: o346.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 21 20:16:08 twilightnode nnrpd[2391269]: o346.scanner.modat.io match_user <anonymous> <anonymous>
Jun 21 20:16:08 twilightnode nnrpd[2391269]: o346.scanner.modat.io no_post anonymous-read
Jun 21 22:08:27 twilightnode nnrpd[2396310]: Reading access from /usr/local/news/etc/readers.conf
Jun 21 22:08:27 twilightnode nnrpd[2396310]: Auth strategy 'localhost' does not match client. Removing.
Jun 21 22:08:27 twilightnode nnrpd[2396310]: 147.185.132.81 res <anonymous>
Jun 21 22:08:27 twilightnode nnrpd[2396310]: 147.185.132.81 no_match_user <anonymous> <localhost>
Jun 21 22:08:27 twilightnode nnrpd[2396310]: 147.185.132.81 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 21 22:08:27 twilightnode nnrpd[2396310]: 147.185.132.81 match_user <anonymous> <anonymous>
Jun 21 22:08:27 twilightnode nnrpd[2396310]: 147.185.132.81 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 21287 73.0%3.0 MB 68.8%
inn 6295 21.6%1.1 MB 24.7%
innd 1499 5.1%279.8 KB 6.3%
nnrpd 75 0.3%8.2 KB 0.2%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 29158 100.0%4.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 9665 42.3%
Negative hits 6950 30.4%
Do not exist 6256 27.4%
Cache misses 0 0.0%
TOTAL: 4 22871 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.001 0.0%1 1.000 1.000 1.000
article cleanup00:00:00.069 0.0%6272 0.000 0.011 0.158
article logging00:00:00.319 0.0%6297 0.000 0.051 0.250
article parse00:00:00.106 0.0%8090 0.000 0.013 0.200
article write00:00:04.518 0.0%3913 0.350 1.155 11.933
data move00:00:00.108 0.0%24454 0.000 0.004 0.025
hisgrep/artcncl00:00:00.001 0.0%1 1.000 1.000 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.829 0.0%22930 0.089 0.211 1.213
history sync00:00:00.078 0.0%411 0.000 0.190 2.000
history write00:00:03.452 0.0%6272 0.182 0.550 1.129
idle24:00:36.746100.0%24331 1322.095 3552.536 14304.524
nntp read00:00:00.778 0.0%23966 0.000 0.032 0.220
overview write00:00:02.109 0.0%3913 0.182 0.539 3.615
perl filter00:00:11.406 0.0%6272 1.102 1.819 17.429
python filter00:00:00.981 0.0%6272 0.023 0.156 1.214
site send00:00:00.127 0.0%3913 0.000 0.032 0.158
TOTAL: 1724:01:05.628100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.056 0.0%6683 0.000 0.008 0.185
article prepare00:00:00.001 0.0%23372 0.000 0.000 0.006
article read00:00:00.689 0.0%3902 0.000 0.177 2.333
backlog stats00:00:00.054 0.0%45732 0.000 0.001 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.368 0.0%22692 0.000 0.016 0.062
data write00:00:00.634 0.0%18849 0.000 0.034 0.091
idle23:58:49.874100.0%45732 1113.779 1887.734 2945.436
status file00:00:06.123 0.0%8604 0.491 0.712 3.948
TOTAL: 923:58:57.799100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:05.817100.0%8 244.000 727.125 1962.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%30 0.000 0.000 0.000
TOTAL: 300:00:05.817100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 9836 3766 4417 1653 38%42:30:06
2newsfeed.endofthelinebbs.com 1 4477 105 3765 607 2%22:09:33
3news.sklaffkom.se 46 107 38 38 31 35%12:09:39
4localhost 25 0 0 0 0 0%00:00:01
5news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 5 78 14420 3909 8220 2291 27%76:59:19
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.6 MB0.0 KB5.9 MB17.5 MB 66%3.3 KB
2newsfeed.endofthelinebbs.com343.0 KB0.0 KB3.0 MB3.3 MB 10%4.8 KB
3news.sklaffkom.se113.8 KB0.0 KB71.3 KB185.1 KB 61%2.7 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 512.0 MB0.0 KB9.0 MB21.0 MB 57%3.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 21 03:00:05 - 03:59:59 69 1.8% 0.02229.6 KB 1.9% 0.06
Jun 21 04:00:00 - 04:59:59 117 3.0% 0.03429.9 KB 3.5% 0.12
Jun 21 05:00:00 - 05:59:59 82 2.1% 0.02300.9 KB 2.5% 0.08
Jun 21 06:00:00 - 06:59:59 72 1.8% 0.02398.4 KB 3.2% 0.11
Jun 21 07:00:00 - 07:59:59 103 2.6% 0.03251.2 KB 2.0% 0.07
Jun 21 08:00:00 - 08:59:59 135 3.5% 0.04477.0 KB 3.9% 0.13
Jun 21 09:00:00 - 09:59:59 201 5.1% 0.06717.0 KB 5.8% 0.20
Jun 21 10:00:00 - 10:59:59 157 4.0% 0.04376.5 KB 3.1% 0.10
Jun 21 11:00:00 - 11:59:59 276 7.1% 0.08685.9 KB 5.6% 0.19
Jun 21 12:00:00 - 12:59:59 206 5.3% 0.06637.9 KB 5.2% 0.18
Jun 21 13:00:00 - 13:59:59 215 5.5% 0.06597.6 KB 4.9% 0.17
Jun 21 14:00:00 - 14:59:59 224 5.7% 0.06588.8 KB 4.8% 0.16
Jun 21 15:00:00 - 15:59:59 188 4.8% 0.05521.6 KB 4.3% 0.14
Jun 21 16:00:00 - 16:59:59 239 6.1% 0.07641.5 KB 5.2% 0.18
Jun 21 17:00:00 - 17:59:59 197 5.0% 0.05621.7 KB 5.1% 0.17
Jun 21 18:00:00 - 18:59:59 190 4.9% 0.05537.6 KB 4.4% 0.15
Jun 21 19:00:00 - 19:59:59 190 4.9% 0.05540.2 KB 4.4% 0.15
Jun 21 20:00:00 - 20:59:59 229 5.9% 0.06795.7 KB 6.5% 0.22
Jun 21 21:00:00 - 21:59:59 162 4.1% 0.04414.3 KB 3.4% 0.12
Jun 21 22:00:00 - 22:59:59 167 4.3% 0.05568.9 KB 4.6% 0.16
Jun 21 23:00:00 - 23:59:59 116 3.0% 0.03491.0 KB 4.0% 0.14
Jun 22 00:00:00 - 00:59:59 119 3.0% 0.03689.6 KB 5.6% 0.19
Jun 22 01:00:00 - 01:59:59 144 3.7% 0.04404.1 KB 3.3% 0.11
Jun 22 02:00:00 - 02:59:59 105 2.7% 0.03342.0 KB 2.8% 0.10
Jun 22 03:00:00 - 03:00:05 10 0.3% 2.0010.6 KB 0.1% 2.11
TOTAL: 24:00:00 3913 100.0% 0.0512.0 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1660 1417 0 0 0 0 0 0 243
2newsfeed.endofthelinebbs.com 691 200 0 0 0 0 0 0 491
3news.sklaffkom.se 31 31 0 0 0 0 0 0 0
TOTAL: 3 2382 1648 0 0 0 0 0 0 734

Unwanted newsgroups [Top 20]:

NewsgroupCount
de.comm.provider.usenet 171
nl.politiek 68
linux.kernel.netdev 61
lada.talk 56
de.alt.folklore.ddr 52
de.alt.folklore.computer 49
pl.soc.polityka 46
linux.kernel.git 44
de.rec.fahrrad 40
de.alt.comm.android 36
alt.checkmate 35
de.soc.recht.misc 35
fido7.ru.military 31
it.discussioni.misteri 31
fr.sci.maths 29
pl.soc.prawo 28
de.alt.talk.unmut 23
de.talk.jokes 23
dk.politik 22
alt.prophecies.nostradamus 21
TOTAL: 228 1648

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 495
EMP (phn path) 178
EMP (phl) 17
User-issued cancel 12
HTML Multipart 7
TOTAL: 5 709

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 3914 3913 0 0 0 0 99%23:59:55
2sklaffkom.se 4174 872 1962 1071 0 2 20%23:59:55
3newsfeed.endofthelinebbs.com 2782 0 0 0 0 6604 0%23:59:54
4weretis.net 135 0 123 0 0 0 0%11:21:35
5sklaff.dev 0 0 0 0 0 3906 0%23:57:03
6thebunker.nu 0 0 0 0 0 3913 0%23:59:55
7usenet.blueworldhosting.com 0 0 0 0 0 3621 0%23:59:55
TOTAL: 7 11005 4785 2085 1071 0 18046 43%155:18:12
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se12.0 MB0.0 KB12.0 MB0.1 KB/s3.1 KB23:59:55
2sklaffkom.se1.3 MB4.5 MB5.8 MB0.1 KB/s3.1 KB23:59:55
3newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
4sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:03
5thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
6usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
7weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s011:21:35
TOTAL: 713.3 MB4.5 MB17.8 MB0.0 KB/s3.1 KB155:18:12
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1147.185.132.81 1 00.0 KB 0 0 000:00:00
2231.224.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
369.5.169.162 1 00.0 KB 0 0 000:00:00
469.5.169.2 1 00.0 KB 0 0 000:00:00
5o346.scanner.modat.io 1 00.0 KB 0 0 000:00:00
6r4-141-8d.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 6 6 00.0 KB 0 0 000:00:05

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 0 0 000:00:01
2*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 4 6 00.0 KB 0 0 000:00:05

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
231.224.132.66.censys-scanner.com 0.029 0.016 0.00000:00:01
r4-141-8d.monitoring.internet-measurement.com 0.008 0.012 0.00000:00:01
69.5.169.2 0.007 0.006 0.00000:00:00
147.185.132.81 0.004 0.013 0.00000:00:00
o346.scanner.modat.io 0.005 0.011 0.00000:00:00
69.5.169.162 0.004 0.015 0.00000:00:00
TOTAL: 6 0.057 0.073 0.00000:00:05

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

SystemConn
231.224.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
69.5.169.2 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