Daily Usenet report

Aug 11 03:00:03 -- Aug 12 03:00:03

Unknown entries from news log file:

First 50 / 76 lines (65.8%)

Aug 11 03:00:56 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 11 04:02:49 twilightnode nnrpd[1102132]: Reading access from /usr/local/news/etc/readers.conf
Aug 11 04:02:49 twilightnode nnrpd[1102132]: Auth strategy 'localhost' does not match client. Removing.
Aug 11 04:02:49 twilightnode nnrpd[1102132]: 69.5.169.62 res <anonymous>
Aug 11 04:02:49 twilightnode nnrpd[1102132]: 69.5.169.62 no_match_user <anonymous> <localhost>
Aug 11 04:02:49 twilightnode nnrpd[1102132]: 69.5.169.62 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 11 04:02:49 twilightnode nnrpd[1102132]: 69.5.169.62 match_user <anonymous> <anonymous>
Aug 11 04:02:49 twilightnode nnrpd[1102132]: 69.5.169.62 no_post anonymous-read
Aug 11 04:03:15 twilightnode nnrpd[1102142]: Reading access from /usr/local/news/etc/readers.conf
Aug 11 04:03:15 twilightnode nnrpd[1102142]: Auth strategy 'localhost' does not match client. Removing.
Aug 11 04:03:15 twilightnode nnrpd[1102142]: 69.5.169.111 res <anonymous>
Aug 11 04:03:15 twilightnode nnrpd[1102142]: 69.5.169.111 no_match_user <anonymous> <localhost>
Aug 11 04:03:15 twilightnode nnrpd[1102142]: 69.5.169.111 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 11 04:03:15 twilightnode nnrpd[1102142]: 69.5.169.111 match_user <anonymous> <anonymous>
Aug 11 04:03:15 twilightnode nnrpd[1102142]: 69.5.169.111 no_post anonymous-read
Aug 11 11:24:23 twilightnode nnrpd[1121364]: Reading access from /usr/local/news/etc/readers.conf
Aug 11 11:24:23 twilightnode nnrpd[1121364]: Auth strategy 'localhost' does not match client. Removing.
Aug 11 11:24:23 twilightnode nnrpd[1121364]: 147.185.132.159 res <anonymous>
Aug 11 11:24:23 twilightnode nnrpd[1121364]: 147.185.132.159 no_match_user <anonymous> <localhost>
Aug 11 11:24:23 twilightnode nnrpd[1121364]: 147.185.132.159 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 11 11:24:23 twilightnode nnrpd[1121364]: 147.185.132.159 match_user <anonymous> <anonymous>
Aug 11 11:24:23 twilightnode nnrpd[1121364]: 147.185.132.159 no_post anonymous-read
Aug 11 11:48:06 twilightnode nnrpd[1122335]: Reading access from /usr/local/news/etc/readers.conf
Aug 11 11:48:06 twilightnode nnrpd[1122335]: Auth strategy 'localhost' does not match client. Removing.
Aug 11 11:48:06 twilightnode nnrpd[1122335]: azpdsg4lwdz3.stretchoid.com res <anonymous>
Aug 11 11:48:06 twilightnode nnrpd[1122335]: azpdsg4lwdz3.stretchoid.com no_match_user <anonymous> <localhost>
Aug 11 11:48:06 twilightnode nnrpd[1122335]: azpdsg4lwdz3.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 11 11:48:06 twilightnode nnrpd[1122335]: azpdsg4lwdz3.stretchoid.com match_user <anonymous> <anonymous>
Aug 11 11:48:06 twilightnode nnrpd[1122335]: azpdsg4lwdz3.stretchoid.com no_post anonymous-read
Aug 11 11:48:16 twilightnode nnrpd[1122336]: Reading access from /usr/local/news/etc/readers.conf
Aug 11 11:48:16 twilightnode nnrpd[1122336]: Auth strategy 'localhost' does not match client. Removing.
Aug 11 11:48:16 twilightnode nnrpd[1122336]: azpdsg4lwdz3.stretchoid.com res <anonymous>
Aug 11 11:48:16 twilightnode nnrpd[1122336]: azpdsg4lwdz3.stretchoid.com no_match_user <anonymous> <localhost>
Aug 11 11:48:16 twilightnode nnrpd[1122336]: azpdsg4lwdz3.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 11 11:48:16 twilightnode nnrpd[1122336]: azpdsg4lwdz3.stretchoid.com match_user <anonymous> <anonymous>
Aug 11 11:48:16 twilightnode nnrpd[1122336]: azpdsg4lwdz3.stretchoid.com no_post anonymous-read
Aug 11 21:31:56 twilightnode nnrpd[1148616]: Reading access from /usr/local/news/etc/readers.conf
Aug 11 21:31:56 twilightnode nnrpd[1148616]: Auth strategy 'localhost' does not match client. Removing.
Aug 11 21:31:56 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com res <anonymous>
Aug 11 21:31:56 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 11 21:31:56 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 11 21:31:56 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 11 21:31:56 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com no_post anonymous-read
Aug 11 21:31:58 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com res <anonymous>
Aug 11 21:31:58 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 11 21:31:58 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 11 21:31:58 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 11 21:31:58 twilightnode nnrpd[1148616]: 98.172.132.66.censys-scanner.com no_post anonymous-read
Aug 11 23:06:07 twilightnode nnrpd[1153628]: Reading access from /usr/local/news/etc/readers.conf
Aug 11 23:06:07 twilightnode nnrpd[1153628]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7103 70.0%1.0 MB 71.6%
innd 1713 16.9%237.0 KB 15.8%
innfeed 1205 11.9%177.3 KB 11.8%
nnrpd 123 1.2%12.6 KB 0.8%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 10145 100.0%1.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14788 47.7%
Negative hits 9169 29.6%
Do not exist 7051 22.7%
Cache misses 0 0.0%
TOTAL: 4 31008 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.014 0.0%3 1.000 4.667 10.000
article cleanup00:00:00.047 0.0%7064 0.000 0.007 0.065
article logging00:00:00.210 0.0%7082 0.000 0.030 0.211
article parse00:00:00.092 0.0%9865 0.000 0.009 0.053
article write00:00:04.697 0.0%5397 0.390 0.870 1.543
data move00:00:00.147 0.0%34358 0.000 0.004 0.061
hisgrep/artcncl00:00:00.006 0.0%3 1.000 2.000 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.218 0.0%31058 0.065 0.136 0.973
history sync00:00:00.068 0.0%353 0.000 0.193 1.000
history write00:00:03.187 0.0%7064 0.100 0.451 0.939
idle23:50:12.937100.0%33135 1213.259 2589.797 11114.222
nntp read00:00:00.578 0.0%32846 0.000 0.018 0.062
overview write00:00:02.389 0.0%5397 0.119 0.443 1.412
perl filter00:00:08.729 0.0%7064 0.659 1.236 3.756
python filter00:00:00.590 0.0%7064 0.000 0.084 0.400
site send00:00:00.080 0.0%5366 0.000 0.015 0.154
TOTAL: 1723:50:37.989100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.011 0.0%5726 0.000 0.002 0.077
article prepare00:00:00.002 0.0%21080 0.000 0.000 0.010
article read00:00:00.558 0.0%3717 0.000 0.150 0.615
backlog stats00:00:00.028 0.0%59062 0.000 0.000 0.010
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.378 0.0%32509 0.000 0.012 0.042
data write00:00:00.656 0.0%27091 0.000 0.024 0.089
idle23:56:52.190100.0%59062 440.984 1459.690 5942.465
status file00:00:00.163 0.0%216 0.000 0.755 6.000
TOTAL: 923:56:53.986100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:26.152100.0%15 74.500 1743.467 10590.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%48 0.000 0.021 0.200
TOTAL: 300:00:26.153100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11884 4562 6078 1244 38%48:02:52
2newsfeed.endofthelinebbs.com 1 4514 682 3514 318 15%19:14:58
3news.tebibyte.org 2 4959 100 4846 13 2%23:59:12
4news.sklaffkom.se 47 113 81 22 10 71%11:23:05
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 79 21470 5425 14460 1585 25%102:40:07
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.8 MB0.0 KB3.9 MB16.7 MB 76%2.9 KB
2newsfeed.endofthelinebbs.com6.9 MB0.0 KB762.4 KB7.6 MB 90%7.8 KB
3news.tebibyte.org383.9 KB0.0 KB96.5 KB480.4 KB 79%4.3 KB
4news.sklaffkom.se239.7 KB0.0 KB21.4 KB261.1 KB 91%2.9 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 520.3 MB0.0 KB4.8 MB25.1 MB 80%3.7 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 11 03:00:03 - 03:59:59 99 1.8% 0.03564.2 KB 2.8% 0.16
Aug 11 04:00:00 - 04:59:59 106 2.0% 0.03433.0 KB 2.1% 0.12
Aug 11 05:00:00 - 05:59:59 115 2.1% 0.03440.0 KB 2.1% 0.12
Aug 11 06:00:00 - 06:59:59 94 1.7% 0.03264.3 KB 1.3% 0.07
Aug 11 07:00:00 - 07:59:59 114 2.1% 0.03472.9 KB 2.3% 0.13
Aug 11 08:00:00 - 08:59:59 277 5.1% 0.081.0 MB 5.1% 0.29
Aug 11 09:00:00 - 09:59:59 257 4.7% 0.071.1 MB 5.6% 0.32
Aug 11 10:00:00 - 10:59:59 190 3.5% 0.05804.6 KB 3.9% 0.22
Aug 11 11:00:00 - 11:59:59 304 5.6% 0.081.5 MB 7.3% 0.41
Aug 11 12:00:00 - 12:59:59 245 4.5% 0.07864.8 KB 4.2% 0.24
Aug 11 13:00:00 - 13:59:59 236 4.4% 0.07640.9 KB 3.1% 0.18
Aug 11 14:00:00 - 14:59:59 304 5.6% 0.08920.6 KB 4.5% 0.26
Aug 11 15:00:00 - 15:59:59 312 5.8% 0.091017.0 KB 5.0% 0.28
Aug 11 16:00:00 - 16:59:59 308 5.7% 0.091.2 MB 5.8% 0.33
Aug 11 17:00:00 - 17:59:59 335 6.2% 0.091.0 MB 5.2% 0.30
Aug 11 18:00:00 - 18:59:59 311 5.7% 0.091.2 MB 6.1% 0.35
Aug 11 19:00:00 - 19:59:59 365 6.7% 0.101.3 MB 6.6% 0.37
Aug 11 20:00:00 - 20:59:59 326 6.0% 0.091.3 MB 6.4% 0.37
Aug 11 21:00:00 - 21:59:59 273 5.0% 0.08982.4 KB 4.8% 0.27
Aug 11 22:00:00 - 22:59:59 214 4.0% 0.06764.1 KB 3.7% 0.21
Aug 11 23:00:00 - 23:59:59 178 3.3% 0.05656.3 KB 3.2% 0.18
Aug 12 00:00:00 - 00:59:59 183 3.4% 0.05653.5 KB 3.2% 0.18
Aug 12 01:00:00 - 01:59:59 142 2.6% 0.04702.1 KB 3.4% 0.20
Aug 12 02:00:00 - 02:59:59 116 2.1% 0.03397.7 KB 1.9% 0.11
Aug 12 03:00:00 - 03:00:03 10 0.2% 3.3325.9 KB 0.1% 8.64
TOTAL: 24:00:00 5414 100.0% 0.0620.0 MB 100.0% 0.24
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1266 1076 0 0 0 0 0 0 190
2newsfeed.endofthelinebbs.com 382 49 0 0 0 0 0 0 333
3news.tebibyte.org 31 30 0 0 0 0 0 0 1
4news.sklaffkom.se 10 10 0 0 0 0 0 0 0
TOTAL: 4 1689 1165 0 0 0 0 0 0 524

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.legal.moderated 86
muc.lists.freebsd.bugs 64
hfx.general 49
de.talk.jokes 45
alt.checkmate 39
alt.usage.english 39
pl.sci.fizyka 38
de.alt.folklore.computer 33
de.comm.software.mozilla.browser 31
alt.atheism 28
linux.debian.vote 27
it-alt.sport.calcio.juventus 24
pl.soc.polityka 23
lada.talk 22
alt.home.repair 20
cn.comp 20
alt.fan.rush-limbaurgh 18
de.soc.recht.misc 18
pl.soc.prawo 18
de.alt.talk.unmut 16
TOTAL: 178 1165

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 336
EMP (phn path) 138
User-issued cancel 18
Too many newsgroups (meow) 10
HTML Multipart 3
Too many newsgroups 1
TOTAL: 6 506

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7264 1603 3778 2 0 51 22%23:59:57
2sklaffkom.se 5844 940 1705 2739 0 27 16%23:59:57
3newsfeed.endofthelinebbs.com 9145 5 4701 1 0 277 0%23:59:55
4weretis.net 860 0 860 0 0 0 0%23:15:54
TOTAL: 4 23113 2548 11044 2742 0 355 11%95:15:43
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org4.2 MB8.0 KB4.2 MB0.0 KB/s2.7 KB23:59:57
2sklaffkom.se1.5 MB12.9 MB14.5 MB0.2 KB/s4.0 KB23:59:57
3newsfeed.endofthelinebbs.com16.1 KB0.9 KB17.1 KB0.0 KB/s2.8 KB23:59:55
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:15:54
TOTAL: 45.7 MB13.0 MB18.6 MB0.1 KB/s3.6 KB95:15:43
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1118.193.58.187 3 00.0 KB 0 0 000:00:11
2azpdsg4lwdz3.stretchoid.com 2 00.0 KB 0 0 000:00:10
3147.185.132.159 1 00.0 KB 0 0 000:00:00
469.5.169.111 1 00.0 KB 0 0 000:00:00
569.5.169.62 1 00.0 KB 0 0 000:00:00
698.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
7o058.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 10 00.0 KB 0 0 000:00:26

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 6 00.0 KB 0 0 000:00:11
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:03
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 4 10 00.0 KB 0 0 000:00:26

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
118.193.58.187 0.012 0.032 0.00000:00:11
azpdsg4lwdz3.stretchoid.com 0.006 0.014 0.00000:00:10
98.172.132.66.censys-scanner.com 0.028 0.011 0.00000:00:03
o058.scanner.modat.io 0.005 0.006 0.00000:00:00
147.185.132.159 0.003 0.015 0.00000:00:00
69.5.169.111 0.002 0.007 0.00000:00:00
69.5.169.62 0.006 0.010 0.00000:00:00
TOTAL: 7 0.062 0.095 0.00000:00:26

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

SystemConn
118.193.58.187 2
98.172.132.66.censys-scanner.com 1
azpdsg4lwdz3.stretchoid.com 1
TOTAL: 3 4

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

CommandCount
#026#003#001 1
?#024 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
69.5.169.111 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