Daily Usenet report

Jun 14 03:00:06 -- Jun 15 03:00:05

Unknown entries from news log file:

First 49 / 49 lines (100.0%)

Jun 14 03:01:26 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 14 05:30:25 twilightnode nnrpd[1889543]: Reading access from /usr/local/news/etc/readers.conf
Jun 14 05:30:25 twilightnode nnrpd[1889543]: Auth strategy 'localhost' does not match client. Removing.
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com res <anonymous>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com no_post anonymous-read
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com res <anonymous>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 14 05:30:25 twilightnode nnrpd[1889543]: 205.186.132.66.censys-scanner.com no_post anonymous-read
Jun 14 08:11:40 twilightnode nnrpd[1896319]: Reading access from /usr/local/news/etc/readers.conf
Jun 14 08:11:40 twilightnode nnrpd[1896319]: Auth strategy 'localhost' does not match client. Removing.
Jun 14 08:11:40 twilightnode nnrpd[1896319]: azpdes9ysn2y.stretchoid.com res <anonymous>
Jun 14 08:11:40 twilightnode nnrpd[1896319]: azpdes9ysn2y.stretchoid.com no_match_user <anonymous> <localhost>
Jun 14 08:11:40 twilightnode nnrpd[1896319]: azpdes9ysn2y.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 14 08:11:40 twilightnode nnrpd[1896319]: azpdes9ysn2y.stretchoid.com match_user <anonymous> <anonymous>
Jun 14 08:11:40 twilightnode nnrpd[1896319]: azpdes9ysn2y.stretchoid.com no_post anonymous-read
Jun 14 08:11:50 twilightnode nnrpd[1896320]: Reading access from /usr/local/news/etc/readers.conf
Jun 14 08:11:50 twilightnode nnrpd[1896320]: Auth strategy 'localhost' does not match client. Removing.
Jun 14 08:11:50 twilightnode nnrpd[1896320]: azpdes9ysn2y.stretchoid.com res <anonymous>
Jun 14 08:11:50 twilightnode nnrpd[1896320]: azpdes9ysn2y.stretchoid.com no_match_user <anonymous> <localhost>
Jun 14 08:11:50 twilightnode nnrpd[1896320]: azpdes9ysn2y.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 14 08:11:50 twilightnode nnrpd[1896320]: azpdes9ysn2y.stretchoid.com match_user <anonymous> <anonymous>
Jun 14 08:11:50 twilightnode nnrpd[1896320]: azpdes9ysn2y.stretchoid.com no_post anonymous-read
Jun 14 20:52:13 twilightnode nnrpd[1933139]: Reading access from /usr/local/news/etc/readers.conf
Jun 14 20:52:13 twilightnode nnrpd[1933139]: Auth strategy 'localhost' does not match client. Removing.
Jun 14 20:52:13 twilightnode nnrpd[1933139]: ali.probe.onyphe.net res <anonymous>
Jun 14 20:52:13 twilightnode nnrpd[1933139]: ali.probe.onyphe.net no_match_user <anonymous> <localhost>
Jun 14 20:52:13 twilightnode nnrpd[1933139]: ali.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 14 20:52:13 twilightnode nnrpd[1933139]: ali.probe.onyphe.net match_user <anonymous> <anonymous>
Jun 14 20:52:13 twilightnode nnrpd[1933139]: ali.probe.onyphe.net no_post anonymous-read
Jun 14 20:52:13 twilightnode nnrpd[1933140]: Reading access from /usr/local/news/etc/readers.conf
Jun 14 20:52:13 twilightnode nnrpd[1933140]: Auth strategy 'localhost' does not match client. Removing.
Jun 14 20:52:13 twilightnode nnrpd[1933140]: stefan.probe.onyphe.net res <anonymous>
Jun 14 20:52:13 twilightnode nnrpd[1933140]: stefan.probe.onyphe.net no_match_user <anonymous> <localhost>
Jun 14 20:52:13 twilightnode nnrpd[1933140]: stefan.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 14 20:52:13 twilightnode nnrpd[1933140]: stefan.probe.onyphe.net match_user <anonymous> <anonymous>
Jun 14 20:52:13 twilightnode nnrpd[1933140]: stefan.probe.onyphe.net no_post anonymous-read
Jun 14 22:59:31 twilightnode pgpverify[1940183]: pgpverify: verification failed
Jun 15 01:04:30 twilightnode nnrpd[1946078]: Reading access from /usr/local/news/etc/readers.conf
Jun 15 01:04:30 twilightnode nnrpd[1946078]: Auth strategy 'localhost' does not match client. Removing.
Jun 15 01:04:30 twilightnode nnrpd[1946078]: 216.226.76.30 res <anonymous>
Jun 15 01:04:30 twilightnode nnrpd[1946078]: 216.226.76.30 no_match_user <anonymous> <localhost>
Jun 15 01:04:30 twilightnode nnrpd[1946078]: 216.226.76.30 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 15 01:04:30 twilightnode nnrpd[1946078]: 216.226.76.30 match_user <anonymous> <anonymous>
Jun 15 01:04:30 twilightnode nnrpd[1946078]: 216.226.76.30 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 6549 72.1%1.1 MB 75.6%
innfeed 1545 17.0%207.5 KB 14.3%
innd 906 10.0%133.9 KB 9.2%
nnrpd 71 0.8%7.6 KB 0.5%
controlchan 17 0.2%3.5 KB 0.2%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 6 9089 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6923 34.6%
Positive hits 6573 32.9%
Do not exist 6506 32.5%
Cache misses 0 0.0%
TOTAL: 4 20002 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.009 0.0%4 1.000 2.250 6.000
article cleanup00:00:00.067 0.0%6537 0.000 0.010 0.091
article logging00:00:00.288 0.0%6565 0.000 0.044 0.185
article parse00:00:00.143 0.0%9107 0.000 0.016 0.200
article write00:00:04.258 0.0%4828 0.316 0.882 8.156
data move00:00:00.086 0.0%23907 0.000 0.004 0.031
hisgrep/artcncl00:00:00.004 0.0%4 0.000 1.000 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.156 0.0%20096 0.083 0.257 1.318
history sync00:00:00.052 0.0%411 0.000 0.127 0.667
history write00:00:03.548 0.0%6537 0.167 0.543 2.186
idle24:00:36.835100.0%22815 826.471 3788.597 20702.103
nntp read00:00:00.690 0.0%22432 0.000 0.031 0.278
overview write00:00:01.990 0.0%4828 0.100 0.412 3.000
perl filter00:00:13.781 0.0%6537 1.123 2.108 12.600
python filter00:00:01.034 0.0%6537 0.000 0.158 0.900
site send00:00:00.130 0.0%4829 0.000 0.027 0.222
TOTAL: 1724:01:08.071100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.012 0.0%4806 0.000 0.002 0.111
article prepare00:00:00.003 0.0%27616 0.000 0.000 0.009
article read00:00:00.763 0.0%4664 0.000 0.164 0.692
backlog stats00:00:00.028 0.0%34141 0.000 0.001 0.137
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.163 0.0%19147 0.000 0.009 0.056
data write00:00:00.401 0.0%14404 0.000 0.028 0.143
idle23:59:49.439100.0%34141 446.891 2530.372 9394.484
status file00:00:00.227 0.0%301 0.000 0.754 18.000
TOTAL: 923:59:51.036100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:14.996100.0%11 59.000 1363.273 10327.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%25 0.000 0.000 0.000
TOTAL: 300:00:14.996100.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
newgroups-request@fido7.org001101
TOTAL001101

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 13679 4840 7133 1706 35%52:08:55
2news.sklaffkom.se 47 90 32 31 27 35%11:10:43
3localhost 25 0 0 0 0 0%00:00:01
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 77 13769 4872 7164 1733 35%63:29:39
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.7 MB0.0 KB11.5 MB27.2 MB 57%4.3 KB
2news.sklaffkom.se142.2 KB0.0 KB65.4 KB207.6 KB 68%3.5 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
4news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 415.8 MB0.0 KB11.6 MB27.4 MB 57%4.2 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 14 03:00:06 - 03:59:59 81 1.7% 0.02250.4 KB 1.6% 0.07
Jun 14 04:00:00 - 04:59:59 71 1.5% 0.02194.8 KB 1.2% 0.05
Jun 14 05:00:00 - 05:59:59 70 1.5% 0.02149.0 KB 0.9% 0.04
Jun 14 06:00:00 - 06:59:59 108 2.2% 0.03297.3 KB 1.9% 0.08
Jun 14 07:00:00 - 07:59:59 95 2.0% 0.03202.7 KB 1.3% 0.06
Jun 14 08:00:00 - 08:59:59 114 2.4% 0.03296.7 KB 1.9% 0.08
Jun 14 09:00:00 - 09:59:59 171 3.6% 0.05522.4 KB 3.3% 0.15
Jun 14 10:00:00 - 10:59:59 333 6.9% 0.091.1 MB 6.9% 0.31
Jun 14 11:00:00 - 11:59:59 428 8.9% 0.121.4 MB 9.2% 0.41
Jun 14 12:00:00 - 12:59:59 361 7.5% 0.101.2 MB 8.0% 0.35
Jun 14 13:00:00 - 13:59:59 332 6.9% 0.091.1 MB 7.0% 0.31
Jun 14 14:00:00 - 14:59:59 277 5.8% 0.08958.3 KB 6.0% 0.27
Jun 14 15:00:00 - 15:59:59 274 5.7% 0.081.0 MB 6.4% 0.29
Jun 14 16:00:00 - 16:59:59 315 6.5% 0.091.1 MB 7.2% 0.32
Jun 14 17:00:00 - 17:59:59 183 3.8% 0.05580.9 KB 3.6% 0.16
Jun 14 18:00:00 - 18:59:59 215 4.5% 0.06646.2 KB 4.0% 0.18
Jun 14 19:00:00 - 19:59:59 208 4.3% 0.06645.6 KB 4.0% 0.18
Jun 14 20:00:00 - 20:59:59 252 5.2% 0.07915.3 KB 5.7% 0.25
Jun 14 21:00:00 - 21:59:59 215 4.5% 0.06726.8 KB 4.5% 0.20
Jun 14 22:00:00 - 22:59:59 246 5.1% 0.071.0 MB 6.7% 0.30
Jun 14 23:00:00 - 23:59:59 144 3.0% 0.04450.8 KB 2.8% 0.13
Jun 15 00:00:00 - 00:59:59 111 2.3% 0.03280.6 KB 1.8% 0.08
Jun 15 01:00:00 - 01:59:59 112 2.3% 0.03406.5 KB 2.5% 0.11
Jun 15 02:00:00 - 02:59:59 89 1.8% 0.02238.9 KB 1.5% 0.07
Jun 15 03:00:00 - 03:00:05 9 0.2% 1.807.4 KB 0.0% 1.48
TOTAL: 23:59:59 4814 100.0% 0.0615.6 MB 100.0% 0.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1708 1566 0 0 0 0 0 0 142
2news.sklaffkom.se 27 27 0 0 0 0 0 0 0
TOTAL: 2 1735 1593 0 0 0 0 0 0 142

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.fan.naked-guy 186
alt.christnet.nudism 73
pl.soc.polityka 42
cn.comp 40
de.etc.finanz.banken+broker 31
nl.politiek 31
de.alt.folklore.computer 29
it.sport.calcio.milan 29
pl.misc.samochody 29
alt.sex.fetish.drmellow 27
alt.bonehead 25
alt.usage.english 24
de.rec.fahrrad 23
fr.sci.maths 22
spline.eisfair 22
dk.politik 20
uk.d-i-y 20
uk.legal.moderated 20
de.test 19
fido7.ru.military 18
TOTAL: 232 1593

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 86
User-issued cancel 11
HTML Multipart 9
Too many newsgroups 4
Too many newsgroups (meow) 2
EMP (md5) 1
HTML post 1
TOTAL: 7 114

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4818 3912 597 305 0 0 81%23:59:47
2sklaffkom.se 5255 922 2105 1780 0 2 17%23:58:50
3weretis.net 38 0 32 0 0 0 0%04:48:37
4sklaff.dev 0 0 0 0 0 4808 0%23:59:41
5thebunker.nu 0 0 0 0 0 4814 0%23:59:55
6usenet.blueworldhosting.com 0 0 0 0 0 4553 0%23:59:55
TOTAL: 6 10111 4834 2734 2085 0 14177 47%124:46:45
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se11.5 MB1.4 MB12.9 MB0.2 KB/s3.1 KB23:59:47
2sklaffkom.se1.5 MB6.8 MB8.4 MB0.1 KB/s3.2 KB23:58:50
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:41
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s004:48:37
TOTAL: 613.0 MB8.2 MB21.2 MB0.0 KB/s3.1 KB124:46:45
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1azpdes9ysn2y.stretchoid.com 2 00.0 KB 0 0 000:00:10
2205.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
3216.226.76.30 1 00.0 KB 0 0 000:00:00
4ali.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
5stefan.probe.onyphe.net 1 00.0 KB 0 0 000:00:03
TOTAL: 5 6 00.0 KB 0 0 000:00:14

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4unresolved 1 00.0 KB 0 0 000:00:00
TOTAL: 4 6 00.0 KB 0 0 000:00:14

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdes9ysn2y.stretchoid.com 0.006 0.024 0.00000:00:10
stefan.probe.onyphe.net 0.002 0.006 0.00000:00:03
205.186.132.66.censys-scanner.com 0.042 0.017 0.00000:00:00
ali.probe.onyphe.net 0.004 0.013 0.00000:00:00
216.226.76.30 0.006 0.009 0.00000:00:00
TOTAL: 5 0.060 0.069 0.00000:00:14

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

SystemConn
ali.probe.onyphe.net 2
205.186.132.66.censys-scanner.com 1
azpdes9ysn2y.stretchoid.com 1
TOTAL: 3 4

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

CommandCount
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 4 4
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