Daily Usenet report

Jun 8 03:00:05 -- Jun 9 03:00:05

Unknown entries from news log file:

First 50 / 93 lines (53.8%)

Jun 8 03:01:22 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 8 05:10:10 twilightnode nnrpd[1483138]: Reading access from /usr/local/news/etc/readers.conf
Jun 8 05:10:10 twilightnode nnrpd[1483138]: Auth strategy 'localhost' does not match client. Removing.
Jun 8 05:10:10 twilightnode nnrpd[1483138]: 37.10.113.219 res <anonymous>
Jun 8 05:10:10 twilightnode nnrpd[1483138]: 37.10.113.219 no_match_user <anonymous> <localhost>
Jun 8 05:10:10 twilightnode nnrpd[1483138]: 37.10.113.219 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 8 05:10:10 twilightnode nnrpd[1483138]: 37.10.113.219 match_user <anonymous> <anonymous>
Jun 8 05:10:10 twilightnode nnrpd[1483138]: 37.10.113.219 no_post anonymous-read
Jun 8 05:24:36 twilightnode nnrpd[1483691]: Reading access from /usr/local/news/etc/readers.conf
Jun 8 05:24:36 twilightnode nnrpd[1483691]: Auth strategy 'localhost' does not match client. Removing.
Jun 8 05:24:36 twilightnode nnrpd[1483691]: 188.240.59.27 res <anonymous>
Jun 8 05:24:36 twilightnode nnrpd[1483691]: 188.240.59.27 no_match_user <anonymous> <localhost>
Jun 8 05:24:36 twilightnode nnrpd[1483691]: 188.240.59.27 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 8 05:24:36 twilightnode nnrpd[1483691]: 188.240.59.27 match_user <anonymous> <anonymous>
Jun 8 05:24:36 twilightnode nnrpd[1483691]: 188.240.59.27 no_post anonymous-read
Jun 8 09:21:04 twilightnode nnrpd[1494180]: Reading access from /usr/local/news/etc/readers.conf
Jun 8 09:21:04 twilightnode nnrpd[1494180]: Auth strategy 'localhost' does not match client. Removing.
Jun 8 09:21:04 twilightnode nnrpd[1494180]: azpdcg0i4caz.stretchoid.com res <anonymous>
Jun 8 09:21:04 twilightnode nnrpd[1494180]: azpdcg0i4caz.stretchoid.com no_match_user <anonymous> <localhost>
Jun 8 09:21:04 twilightnode nnrpd[1494180]: azpdcg0i4caz.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 8 09:21:04 twilightnode nnrpd[1494180]: azpdcg0i4caz.stretchoid.com match_user <anonymous> <anonymous>
Jun 8 09:21:04 twilightnode nnrpd[1494180]: azpdcg0i4caz.stretchoid.com no_post anonymous-read
Jun 8 09:21:14 twilightnode nnrpd[1494181]: Reading access from /usr/local/news/etc/readers.conf
Jun 8 09:21:14 twilightnode nnrpd[1494181]: Auth strategy 'localhost' does not match client. Removing.
Jun 8 09:21:14 twilightnode nnrpd[1494181]: azpdcg0i4caz.stretchoid.com res <anonymous>
Jun 8 09:21:14 twilightnode nnrpd[1494181]: azpdcg0i4caz.stretchoid.com no_match_user <anonymous> <localhost>
Jun 8 09:21:14 twilightnode nnrpd[1494181]: azpdcg0i4caz.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 8 09:21:14 twilightnode nnrpd[1494181]: azpdcg0i4caz.stretchoid.com match_user <anonymous> <anonymous>
Jun 8 09:21:14 twilightnode nnrpd[1494181]: azpdcg0i4caz.stretchoid.com no_post anonymous-read
Jun 8 18:59:54 twilightnode nnrpd[1520318]: Reading access from /usr/local/news/etc/readers.conf
Jun 8 18:59:54 twilightnode nnrpd[1520318]: Auth strategy 'localhost' does not match client. Removing.
Jun 8 18:59:54 twilightnode nnrpd[1520318]: 69.5.169.118 res <anonymous>
Jun 8 18:59:54 twilightnode nnrpd[1520318]: 69.5.169.118 no_match_user <anonymous> <localhost>
Jun 8 18:59:54 twilightnode nnrpd[1520318]: 69.5.169.118 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 8 18:59:54 twilightnode nnrpd[1520318]: 69.5.169.118 match_user <anonymous> <anonymous>
Jun 8 18:59:54 twilightnode nnrpd[1520318]: 69.5.169.118 no_post anonymous-read
Jun 8 19:14:30 twilightnode nnrpd[1521077]: Reading access from /usr/local/news/etc/readers.conf
Jun 8 19:14:30 twilightnode nnrpd[1521077]: Auth strategy 'localhost' does not match client. Removing.
Jun 8 19:14:30 twilightnode nnrpd[1521077]: 198.235.24.90 res <anonymous>
Jun 8 19:14:30 twilightnode nnrpd[1521077]: 198.235.24.90 no_match_user <anonymous> <localhost>
Jun 8 19:14:30 twilightnode nnrpd[1521077]: 198.235.24.90 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 8 19:14:30 twilightnode nnrpd[1521077]: 198.235.24.90 match_user <anonymous> <anonymous>
Jun 8 19:14:30 twilightnode nnrpd[1521077]: 198.235.24.90 no_post anonymous-read
Jun 8 21:37:50 twilightnode nnrpd[1527605]: Reading access from /usr/local/news/etc/readers.conf
Jun 8 21:37:50 twilightnode nnrpd[1527605]: Auth strategy 'localhost' does not match client. Removing.
Jun 8 21:37:50 twilightnode nnrpd[1527605]: 69.5.169.57 res <anonymous>
Jun 8 21:37:50 twilightnode nnrpd[1527605]: 69.5.169.57 no_match_user <anonymous> <localhost>
Jun 8 21:37:50 twilightnode nnrpd[1527605]: 69.5.169.57 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 8 21:37:50 twilightnode nnrpd[1527605]: 69.5.169.57 match_user <anonymous> <anonymous>
Jun 8 21:37:50 twilightnode nnrpd[1527605]: 69.5.169.57 no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
inn 8272 64.7%1.2 MB 69.1%
innd 2828 22.1%341.5 KB 18.9%
innfeed 1509 11.8%200.3 KB 11.1%
nnrpd 177 1.4%17.5 KB 1.0%
controlchan 4 0.0%0.3 KB 0.0%
TOTAL: 5 12790 100.0%1.8 MB100.0%

History cache:

ReasonCount%Count
Negative hits 8736 34.6%
Positive hits 8259 32.7%
Do not exist 8227 32.6%
Cache misses 35 0.1%
TOTAL: 4 25257 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.003 0.0%2 1.000 1.500 2.000
article cleanup00:00:00.076 0.0%8247 0.000 0.009 0.100
article logging00:00:00.244 0.0%8277 0.000 0.029 0.167
article parse00:00:00.157 0.0%11114 0.000 0.014 0.167
article write00:00:03.670 0.0%4547 0.284 0.807 2.455
data move00:00:00.059 0.0%21980 0.000 0.003 0.025
hisgrep/artcncl00:00:00.001 0.0%2 0.000 0.500 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.727 0.0%25332 0.065 0.187 1.552
history sync00:00:00.055 0.0%412 0.000 0.133 1.000
history write00:00:03.746 0.0%8247 0.190 0.454 1.000
idle24:00:42.233100.0%21579 798.899 4005.850 27300.864
nntp read00:00:00.621 0.0%21236 0.000 0.029 0.485
overview write00:00:01.961 0.0%4547 0.097 0.431 3.182
perl filter00:00:11.980 0.0%8247 0.597 1.453 20.267
python filter00:00:00.831 0.0%8248 0.013 0.101 1.200
site send00:00:00.148 0.0%4547 0.000 0.033 0.182
TOTAL: 1724:01:10.512100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.015 0.0%4611 0.000 0.003 0.053
article prepare00:00:00.002 0.0%26452 0.000 0.000 0.006
article read00:00:00.709 0.0%4604 0.000 0.154 0.917
backlog stats00:00:00.018 0.0%28797 0.000 0.001 0.010
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.173 0.0%16185 0.000 0.011 0.078
data write00:00:00.386 0.0%11772 0.000 0.033 0.262
idle23:51:43.860100.0%28797 1318.725 2983.084 8966.896
status file00:00:00.269 0.0%285 0.000 0.944 15.000
TOTAL: 923:51:45.432100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:14.710100.0%25 12.333 588.400 10254.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%114 0.000 0.009 0.200
TOTAL: 300:00:14.711100.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 4 17149 4613 8981 3555 26%53:59:01
2news.sklaffkom.se 34 80 32 26 22 40%09:26:19
3localhost 26 1 1 0 0 100%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
5news.twilightnode.org 1 0 0 0 0 0%00:00:00
TOTAL: 5 66 17230 4646 9007 3577 26%63:35:20
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.5 MB0.0 KB10.5 MB25.0 MB 57%3.1 KB
2news.sklaffkom.se236.4 KB0.0 KB79.4 KB315.8 KB 74%5.8 KB
3localhost0.6 KB0.0 KB0.0 KB0.6 KB100%0.6 KB
4news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5news.twilightnode.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 514.7 MB0.0 KB10.6 MB25.3 MB 58%3.2 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 8 03:00:05 - 03:59:59 78 1.7% 0.02331.5 KB 2.3% 0.09
Jun 8 04:00:00 - 04:59:59 70 1.5% 0.02334.5 KB 2.3% 0.09
Jun 8 05:00:00 - 05:59:59 82 1.8% 0.02340.4 KB 2.3% 0.09
Jun 8 06:00:00 - 06:59:59 103 2.3% 0.03308.1 KB 2.1% 0.09
Jun 8 07:00:00 - 07:59:59 144 3.2% 0.04415.6 KB 2.8% 0.12
Jun 8 08:00:00 - 08:59:59 185 4.1% 0.05443.4 KB 3.0% 0.12
Jun 8 09:00:00 - 09:59:59 189 4.2% 0.05449.3 KB 3.1% 0.12
Jun 8 10:00:00 - 10:59:59 206 4.5% 0.06610.7 KB 4.2% 0.17
Jun 8 11:00:00 - 11:59:59 231 5.1% 0.06575.1 KB 3.9% 0.16
Jun 8 12:00:00 - 12:59:59 168 3.7% 0.05557.0 KB 3.8% 0.15
Jun 8 13:00:00 - 13:59:59 198 4.4% 0.06472.3 KB 3.2% 0.13
Jun 8 14:00:00 - 14:59:59 165 3.6% 0.05438.6 KB 3.0% 0.12
Jun 8 15:00:00 - 15:59:59 226 5.0% 0.06703.4 KB 4.8% 0.20
Jun 8 16:00:00 - 16:59:59 226 5.0% 0.06782.1 KB 5.3% 0.22
Jun 8 17:00:00 - 17:59:59 302 6.7% 0.08894.2 KB 6.1% 0.25
Jun 8 18:00:00 - 18:59:59 292 6.4% 0.081003.9 KB 6.8% 0.28
Jun 8 19:00:00 - 19:59:59 239 5.3% 0.07649.6 KB 4.4% 0.18
Jun 8 20:00:00 - 20:59:59 222 4.9% 0.06666.1 KB 4.5% 0.19
Jun 8 21:00:00 - 21:59:59 216 4.8% 0.06573.8 KB 3.9% 0.16
Jun 8 22:00:00 - 22:59:59 220 4.8% 0.06623.7 KB 4.2% 0.17
Jun 8 23:00:00 - 23:59:59 190 4.2% 0.05683.3 KB 4.7% 0.19
Jun 9 00:00:00 - 00:59:59 139 3.1% 0.04357.1 KB 2.4% 0.10
Jun 9 01:00:00 - 01:59:59 345 7.6% 0.102.2 MB 15.0% 0.61
Jun 9 02:00:00 - 02:59:59 94 2.1% 0.03251.1 KB 1.7% 0.07
Jun 9 03:00:00 - 03:00:05 11 0.2% 2.2011.5 KB 0.1% 2.30
TOTAL: 24:00:00 4541 100.0% 0.0514.3 MB 100.0% 0.17
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 3709 1607 0 0 0 0 0 0 2102
2news.sklaffkom.se 22 20 0 0 0 0 0 0 2
TOTAL: 2 3731 1627 0 0 0 0 0 0 2104

Unwanted newsgroups [Top 20]:

NewsgroupCount
fido7.ru.news 150
uk.legal.moderated 56
pl.soc.prawo 44
alt.usage.english 41
it.sport.calcio.milan 41
pl.misc.elektronika 41
uk.d-i-y 38
cn.comp 37
de.rec.fahrrad 37
nl.politiek 29
linux.debian.changes 27
pl.misc.samochody 25
it.hobby.cucina 24
de.etc.finanz.banken+broker 23
fido7.crimea.talk 23
it-alt.sport.calcio.juventus 22
fido7.ru.military 21
alt.recovery.aa 20
de.sci.mathematik 20
de.talk.jokes 20
TOTAL: 223 1627

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 2052
User-issued cancel 11
Too many newsgroups (meow) 5
EMP (md5) 4
Binary Image: misplaced jpg 1
HTML Multipart 1
TOTAL: 6 2074

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4537 4531 0 4 0 6 99%23:59:56
2sklaffkom.se 4962 920 2438 1184 0 84 18%23:59:56
3weretis.net 41 1 30 0 0 0 2%04:25:32
4sklaff.dev 0 0 0 0 0 4535 0%23:59:42
5thebunker.nu 0 0 0 0 0 4541 0%23:59:56
6usenet.blueworldhosting.com 0 0 0 0 0 4166 0%23:59:56
TOTAL: 6 9540 5452 2468 1188 0 13332 57%124:24:58
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se14.3 MB4.9 KB14.3 MB0.2 KB/s3.2 KB23:59:56
2sklaffkom.se1.5 MB4.0 MB5.5 MB0.1 KB/s2.7 KB23:59:56
3weretis.net0.6 KB0.0 KB0.6 KB0.0 KB/s0.6 KB04:25:32
4sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
5thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
6usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
TOTAL: 615.8 MB4.0 MB19.8 MB0.0 KB/s3.1 KB124:24:58
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
169.5.169.118 2 00.0 KB 0 0 000:00:00
2azpdcg0i4caz.stretchoid.com 2 00.0 KB 0 0 000:00:10
3188.240.59.27 1 00.0 KB 0 0 000:00:00
4195.140.214.25 1 00.0 KB 0 0 000:00:00
5198.235.24.90 1 00.0 KB 0 0 000:00:00
634.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
737.10.113.219 1 00.0 KB 0 0 000:00:00
869.5.169.51 1 00.0 KB 0 0 000:00:00
969.5.169.55 1 00.0 KB 0 0 000:00:00
1069.5.169.57 1 00.0 KB 0 0 000:00:00
11twilightnode.org 1 00.0 KB 0 1 000:00:00
TOTAL: 11 13 00.0 KB 0 1 000:00:14

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 9 00.0 KB 0 0 000:00:01
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:02
4*.org 1 00.0 KB 0 1 000:00:00
TOTAL: 4 13 00.0 KB 0 1 000:00:14

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcg0i4caz.stretchoid.com 0.007 0.021 0.00000:00:10
34.195.132.66.censys-scanner.com 0.027 0.018 0.00000:00:02
188.240.59.27 0.006 0.011 0.00000:00:00
198.235.24.90 0.003 0.012 0.00000:00:00
69.5.169.118 0.012 0.020 0.00000:00:00
69.5.169.57 0.003 0.012 0.00000:00:00
37.10.113.219 0.002 0.019 0.00000:00:00
69.5.169.51 0.003 0.007 0.00000:00:00
195.140.214.25 0.003 0.008 0.00000:00:00
69.5.169.55 0.004 0.012 0.00000:00:00
twilightnode.org 0.005 0.019 0.00000:00:00
TOTAL: 11 0.075 0.159 0.00000:00:14

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

SystemConn
69.5.169.55 6
69.5.169.51 4
69.5.169.118 3
34.195.132.66.censys-scanner.com 1
azpdcg0i4caz.stretchoid.com 1
TOTAL: 5 15

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

CommandCount
#026#003#001#002?#001 2
?#011?#023?#024 2
#027#027?I?!?3?-?X#002#004#013?|?M?#003#017#014?.?l#0032... 1
?E?#015?#002? 1
?kg H?()d?8?N?\?#026X?mJb?`?X 1
Accept-Encoding: gzip 1
Accept: */* 1
Connection: close 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
MGLNDD_172.234.115.63_119 1
User-Agent: Mozilla/5.0 (compatible; Inf... 1
TOTAL: 13 15

NNRP client timeouts [Top 20]:

SystemConnPeer
69.5.169.118 2 2
188.240.59.27 1 1
69.5.169.51 1 1
69.5.169.55 1 1
69.5.169.57 1 1
TOTAL: 5 6 9
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