Daily Usenet report

Jun 28 03:00:06 -- Jun 29 03:00:05

Unknown entries from news log file:

First 50 / 95 lines (52.6%)

Jun 28 03:01:22 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 28 06:15:41 twilightnode nnrpd[2811139]: Reading access from /usr/local/news/etc/readers.conf
Jun 28 06:15:41 twilightnode nnrpd[2811139]: Auth strategy 'localhost' does not match client. Removing.
Jun 28 06:15:41 twilightnode nnrpd[2811139]: 193.176.31.218 res <anonymous>
Jun 28 06:15:41 twilightnode nnrpd[2811139]: 193.176.31.218 no_match_user <anonymous> <localhost>
Jun 28 06:15:41 twilightnode nnrpd[2811139]: 193.176.31.218 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 28 06:15:41 twilightnode nnrpd[2811139]: 193.176.31.218 match_user <anonymous> <anonymous>
Jun 28 06:15:41 twilightnode nnrpd[2811139]: 193.176.31.218 no_post anonymous-read
Jun 28 06:15:47 twilightnode nnrpd[2811140]: Reading access from /usr/local/news/etc/readers.conf
Jun 28 06:15:47 twilightnode nnrpd[2811140]: Auth strategy 'localhost' does not match client. Removing.
Jun 28 06:15:47 twilightnode nnrpd[2811140]: 194.50.235.142 res <anonymous>
Jun 28 06:15:47 twilightnode nnrpd[2811140]: 194.50.235.142 no_match_user <anonymous> <localhost>
Jun 28 06:15:47 twilightnode nnrpd[2811140]: 194.50.235.142 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 28 06:15:47 twilightnode nnrpd[2811140]: 194.50.235.142 match_user <anonymous> <anonymous>
Jun 28 06:15:47 twilightnode nnrpd[2811140]: 194.50.235.142 no_post anonymous-read
Jun 28 14:18:18 twilightnode nnrpd[2831882]: Reading access from /usr/local/news/etc/readers.conf
Jun 28 14:18:18 twilightnode nnrpd[2831882]: Auth strategy 'localhost' does not match client. Removing.
Jun 28 14:18:18 twilightnode nnrpd[2831882]: azpdwsa8kbcf.stretchoid.com res <anonymous>
Jun 28 14:18:18 twilightnode nnrpd[2831882]: azpdwsa8kbcf.stretchoid.com no_match_user <anonymous> <localhost>
Jun 28 14:18:18 twilightnode nnrpd[2831882]: azpdwsa8kbcf.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 28 14:18:18 twilightnode nnrpd[2831882]: azpdwsa8kbcf.stretchoid.com match_user <anonymous> <anonymous>
Jun 28 14:18:18 twilightnode nnrpd[2831882]: azpdwsa8kbcf.stretchoid.com no_post anonymous-read
Jun 28 14:18:28 twilightnode nnrpd[2831883]: Reading access from /usr/local/news/etc/readers.conf
Jun 28 14:18:28 twilightnode nnrpd[2831883]: Auth strategy 'localhost' does not match client. Removing.
Jun 28 14:18:28 twilightnode nnrpd[2831883]: azpdwsa8kbcf.stretchoid.com res <anonymous>
Jun 28 14:18:28 twilightnode nnrpd[2831883]: azpdwsa8kbcf.stretchoid.com no_match_user <anonymous> <localhost>
Jun 28 14:18:28 twilightnode nnrpd[2831883]: azpdwsa8kbcf.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 28 14:18:28 twilightnode nnrpd[2831883]: azpdwsa8kbcf.stretchoid.com match_user <anonymous> <anonymous>
Jun 28 14:18:28 twilightnode nnrpd[2831883]: azpdwsa8kbcf.stretchoid.com no_post anonymous-read
Jun 28 18:36:13 twilightnode nnrpd[2842714]: Reading access from /usr/local/news/etc/readers.conf
Jun 28 18:36:13 twilightnode nnrpd[2842714]: Auth strategy 'localhost' does not match client. Removing.
Jun 28 18:36:13 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com res <anonymous>
Jun 28 18:36:13 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 28 18:36:13 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 28 18:36:13 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 28 18:36:13 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com no_post anonymous-read
Jun 28 18:36:15 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com res <anonymous>
Jun 28 18:36:15 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 28 18:36:15 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 28 18:36:15 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 28 18:36:15 twilightnode nnrpd[2842714]: 231.224.132.66.censys-scanner.com no_post anonymous-read
Jun 28 18:57:18 twilightnode nnrpd[2843652]: Reading access from /usr/local/news/etc/readers.conf
Jun 28 18:57:18 twilightnode nnrpd[2843652]: Auth strategy 'localhost' does not match client. Removing.
Jun 28 18:57:18 twilightnode nnrpd[2843652]: 152.32.183.13 res <anonymous>
Jun 28 18:57:18 twilightnode nnrpd[2843652]: 152.32.183.13 no_match_user <anonymous> <localhost>
Jun 28 18:57:18 twilightnode nnrpd[2843652]: 152.32.183.13 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 28 18:57:18 twilightnode nnrpd[2843652]: 152.32.183.13 match_user <anonymous> <anonymous>
Jun 28 18:57:18 twilightnode nnrpd[2843652]: 152.32.183.13 no_post anonymous-read
Jun 28 18:57:18 twilightnode nnrpd[2843653]: Reading access from /usr/local/news/etc/readers.conf
Jun 28 18:57:18 twilightnode nnrpd[2843653]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 41320 83.7%5.7 MB 79.1%
inn 6421 13.0%1.2 MB 16.7%
innd 1498 3.0%288.2 KB 3.9%
nnrpd 152 0.3%16.5 KB 0.2%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 49393 100.0%7.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15656 51.8%
Negative hits 8214 27.2%
Do not exist 6378 21.1%
Cache misses 5 0.0%
TOTAL: 4 30253 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.011 0.0%3 1.000 3.667 8.000
article cleanup00:00:00.057 0.0%6394 0.000 0.009 0.071
article logging00:00:00.241 0.0%6424 0.000 0.038 0.143
article parse00:00:00.086 0.0%8478 0.000 0.010 0.143
article write00:00:04.398 0.0%4477 0.365 0.982 8.450
data move00:00:00.063 0.0%31464 0.000 0.002 0.029
hisgrep/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.409 0.0%30331 0.084 0.145 0.813
history sync00:00:00.068 0.0%417 0.000 0.163 1.500
history write00:00:03.471 0.0%6394 0.143 0.543 1.158
idle24:00:40.981100.0%30999 1257.365 2788.509 20704.241
nntp read00:00:00.853 0.0%30667 0.000 0.028 0.256
overview write00:00:02.237 0.0%4477 0.111 0.500 2.200
perl filter00:00:10.169 0.0%6394 0.938 1.590 14.286
python filter00:00:00.725 0.0%6394 0.000 0.113 0.786
site send00:00:00.107 0.0%4477 0.000 0.024 0.231
TOTAL: 1724:01:07.876100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.044 0.0%10083 0.000 0.004 0.048
article prepare00:00:00.006 0.0%25752 0.000 0.000 0.007
article read00:00:00.656 0.0%4464 0.000 0.147 1.500
backlog stats00:00:00.076 0.0%68302 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.515 0.0%33084 0.000 0.016 0.060
data write00:00:00.862 0.0%28706 0.000 0.030 0.084
idle23:58:48.377100.0%68302 858.703 1263.922 1803.748
status file00:00:10.010 0.0%17102 0.420 0.585 0.900
TOTAL: 923:59:00.546100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:34.173 99.9%21 88.000 1627.286 10583.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.045 0.1%3256 0.000 0.014 0.014
TOTAL: 300:00:34.218100.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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 9470 3995 4138 1337 42%39:44:18
2newsfeed.endofthelinebbs.com 1 5021 227 4219 575 4%25:33:48
3news.tebibyte.org 1 5206 55 5052 99 1%20:19:12
4news.sklaffkom.se 31 60 29 12 19 48%07:13:01
5localhost 25 0 0 0 0 0%00:00:00
6news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 6 63 19757 4306 13421 2030 21%93:00:19
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net13.5 MB0.0 KB3.6 MB17.2 MB 78%3.3 KB
2newsfeed.endofthelinebbs.com1.3 MB0.0 KB918.1 KB2.2 MB 59%2.9 KB
3news.tebibyte.org360.1 KB0.0 KB298.7 KB658.8 KB 54%4.3 KB
4news.sklaffkom.se113.5 KB0.0 KB40.5 KB154.0 KB 73%3.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: 615.3 MB0.0 KB4.9 MB20.2 MB 75%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 28 03:00:06 - 03:59:59 78 1.7% 0.02353.6 KB 2.2% 0.10
Jun 28 04:00:00 - 04:59:59 82 1.8% 0.02260.9 KB 1.6% 0.07
Jun 28 05:00:00 - 05:59:59 99 2.2% 0.03324.5 KB 2.0% 0.09
Jun 28 06:00:00 - 06:59:59 103 2.3% 0.03427.7 KB 2.6% 0.12
Jun 28 07:00:00 - 07:59:59 152 3.4% 0.04705.6 KB 4.4% 0.20
Jun 28 08:00:00 - 08:59:59 181 4.0% 0.05540.8 KB 3.3% 0.15
Jun 28 09:00:00 - 09:59:59 216 4.8% 0.06670.6 KB 4.1% 0.19
Jun 28 10:00:00 - 10:59:59 224 5.0% 0.06686.7 KB 4.2% 0.19
Jun 28 11:00:00 - 11:59:59 252 5.6% 0.07760.6 KB 4.7% 0.21
Jun 28 12:00:00 - 12:59:59 217 4.9% 0.06589.7 KB 3.6% 0.16
Jun 28 13:00:00 - 13:59:59 223 5.0% 0.06612.6 KB 3.8% 0.17
Jun 28 14:00:00 - 14:59:59 254 5.7% 0.07908.5 KB 5.6% 0.25
Jun 28 15:00:00 - 15:59:59 184 4.1% 0.05443.1 KB 2.7% 0.12
Jun 28 16:00:00 - 16:59:59 212 4.7% 0.06623.3 KB 3.9% 0.17
Jun 28 17:00:00 - 17:59:59 237 5.3% 0.071.0 MB 6.6% 0.30
Jun 28 18:00:00 - 18:59:59 269 6.0% 0.071.1 MB 6.7% 0.30
Jun 28 19:00:00 - 19:59:59 202 4.5% 0.06610.3 KB 3.8% 0.17
Jun 28 20:00:00 - 20:59:59 202 4.5% 0.06725.2 KB 4.5% 0.20
Jun 28 21:00:00 - 21:59:59 239 5.3% 0.07643.8 KB 4.0% 0.18
Jun 28 22:00:00 - 22:59:59 230 5.1% 0.06645.5 KB 4.0% 0.18
Jun 28 23:00:00 - 23:59:59 231 5.2% 0.061.9 MB 11.7% 0.53
Jun 29 00:00:00 - 00:59:59 138 3.1% 0.04569.8 KB 3.5% 0.16
Jun 29 01:00:00 - 01:59:59 125 2.8% 0.03508.6 KB 3.1% 0.14
Jun 29 02:00:00 - 02:59:59 114 2.5% 0.03503.5 KB 3.1% 0.14
Jun 29 03:00:00 - 03:00:05 9 0.2% 1.807.5 KB 0.0% 1.49
TOTAL: 23:59:59 4473 100.0% 0.0515.8 MB 100.0% 0.19
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1343 1064 0 0 0 0 0 0 279
2newsfeed.endofthelinebbs.com 578 67 0 0 0 0 0 0 511
3news.sklaffkom.se 19 19 0 0 0 0 0 0 0
4news.tebibyte.org 8 6 0 0 0 0 0 0 2
TOTAL: 4 1948 1156 0 0 0 0 0 0 792

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 50
fido7.ru.home 48
de.alt.folklore.computer 43
fr.sci.maths 31
alt.recovery.aa 30
de.alt.talk.unmut 30
spline.eisfair 30
de.comm.provider.usenet 29
uk.tech.broadcast 29
uk.telecom.broadband 29
hfx.general 23
pl.soc.prawo 22
lada.talk 21
fido7.ukrnews 19
alt.checkmate 18
it.cultura.filosofia 18
aus.politics 17
de.soc.recht.misc 17
linux.debian.ports.hurd 15
pl.soc.polityka 14
TOTAL: 218 1156

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 523
EMP (phn path) 223
HTML Multipart 6
Too many newsgroups 5
User-issued cancel 5
TOTAL: 5 762

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 29
newsfeed.endofthelinebbs.com 1
TOTAL: 2 30
TOTAL: 1 30

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4474 4439 0 34 0 0 99%23:59:52
2sklaffkom.se 4794 819 2485 1158 0 0 17%23:59:52
3news.tebibyte.org 2839 0 0 0 0 7301 0%23:59:52
4newsfeed.endofthelinebbs.com 2783 0 0 0 0 7032 0%23:59:52
5weretis.net 296 0 291 0 0 0 0%18:28:13
6sklaff.dev 0 0 0 0 0 4462 0%23:57:03
7thebunker.nu 0 0 0 0 0 4473 0%23:59:52
8usenet.blueworldhosting.com 0 0 0 0 0 4193 0%23:59:52
TOTAL: 8 15186 5258 2776 1192 0 27461 34%186:24:28
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se15.7 MB64.3 KB15.8 MB0.2 KB/s3.6 KB23:59:52
2sklaffkom.se1.3 MB4.7 MB6.0 MB0.1 KB/s3.1 KB23:59:52
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:03
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s018:28:13
TOTAL: 817.0 MB4.8 MB21.8 MB0.0 KB/s3.5 KB186:24:28
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1152.32.183.13 3 00.0 KB 0 0 000:00:11
292.224.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:03
3azpdwsa8kbcf.stretchoid.com 2 00.0 KB 0 0 000:00:10
4193.176.31.218 1 00.0 KB 0 0 000:00:00
5194.187.176.33 1 00.0 KB 0 0 000:00:05
6194.50.235.142 1 00.0 KB 0 0 000:00:00
7231.224.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
TOTAL: 7 12 00.0 KB 0 0 000:00:34

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 6 00.0 KB 0 0 000:00:17
2*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:06
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
TOTAL: 3 12 00.0 KB 0 0 000:00:34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
152.32.183.13 0.012 0.030 0.00000:00:11
azpdwsa8kbcf.stretchoid.com 0.016 0.030 0.00000:00:10
194.187.176.33 0.008 0.017 0.00000:00:05
92.224.132.66.censys-scanner.com 0.041 0.031 0.00000:00:03
231.224.132.66.censys-scanner.com 0.027 0.014 0.00000:00:02
193.176.31.218 0.002 0.031 0.00000:00:00
194.50.235.142 0.002 0.030 0.00000:00:00
TOTAL: 7 0.108 0.183 0.00000:00:34

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

SystemConn
152.32.183.13 3
231.224.132.66.censys-scanner.com 1
92.224.132.66.censys-scanner.com 1
azpdwsa8kbcf.stretchoid.com 1
TOTAL: 4 6

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

CommandCount
EHLO www.censys.io 2
#026#003#001 1
?#024 1
?=g?#006?<? ?A)r?#016/?#015?1?#035;?r8?... 1
MGLNDD_172.234.115.63_119 1
TOTAL: 5 6

NNRP client timeouts [Top 20]:

SystemConnPeer
194.50.235.142 1 1
92.224.132.66.censys-scanner.com 1 2
TOTAL: 2 2 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