Daily Usenet report

Jul 27 03:00:06 -- Jul 28 03:00:05

Unknown entries from news log file:

First 50 / 151 lines (33.1%)

Jul 27 03:01:21 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 27 03:34:06 twilightnode nnrpd[90198]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 03:34:06 twilightnode nnrpd[90198]: Auth strategy 'localhost' does not match client. Removing.
Jul 27 03:34:06 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com res <anonymous>
Jul 27 03:34:06 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 27 03:34:06 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 27 03:34:06 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 27 03:34:06 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com no_post anonymous-read
Jul 27 03:34:07 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com res <anonymous>
Jul 27 03:34:07 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 27 03:34:07 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 27 03:34:07 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 27 03:34:07 twilightnode nnrpd[90198]: 161.186.132.66.censys-scanner.com no_post anonymous-read
Jul 27 05:39:11 twilightnode nnrpd[95293]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 05:39:11 twilightnode nnrpd[95293]: Auth strategy 'localhost' does not match client. Removing.
Jul 27 05:39:11 twilightnode nnrpd[95293]: 69.5.169.221 res <anonymous>
Jul 27 05:39:11 twilightnode nnrpd[95293]: 69.5.169.221 no_match_user <anonymous> <localhost>
Jul 27 05:39:11 twilightnode nnrpd[95293]: 69.5.169.221 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 27 05:39:11 twilightnode nnrpd[95293]: 69.5.169.221 match_user <anonymous> <anonymous>
Jul 27 05:39:11 twilightnode nnrpd[95293]: 69.5.169.221 no_post anonymous-read
Jul 27 05:39:27 twilightnode nnrpd[95349]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 05:39:27 twilightnode nnrpd[95349]: Auth strategy 'localhost' does not match client. Removing.
Jul 27 05:39:27 twilightnode nnrpd[95349]: 31.14.254.69 res <anonymous>
Jul 27 05:39:27 twilightnode nnrpd[95349]: 31.14.254.69 no_match_user <anonymous> <localhost>
Jul 27 05:39:27 twilightnode nnrpd[95349]: 31.14.254.69 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 27 05:39:27 twilightnode nnrpd[95349]: 31.14.254.69 match_user <anonymous> <anonymous>
Jul 27 05:39:27 twilightnode nnrpd[95349]: 31.14.254.69 no_post anonymous-read
Jul 27 05:54:53 twilightnode nnrpd[96011]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 05:54:53 twilightnode nnrpd[96011]: Auth strategy 'localhost' does not match client. Removing.
Jul 27 05:54:53 twilightnode nnrpd[96011]: 165.154.138.151 res <anonymous>
Jul 27 05:54:53 twilightnode nnrpd[96011]: 165.154.138.151 no_match_user <anonymous> <localhost>
Jul 27 05:54:53 twilightnode nnrpd[96011]: 165.154.138.151 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 27 05:54:53 twilightnode nnrpd[96011]: 165.154.138.151 match_user <anonymous> <anonymous>
Jul 27 05:54:53 twilightnode nnrpd[96011]: 165.154.138.151 no_post anonymous-read
Jul 27 05:54:53 twilightnode nnrpd[96012]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 05:54:53 twilightnode nnrpd[96012]: Auth strategy 'localhost' does not match client. Removing.
Jul 27 05:54:53 twilightnode nnrpd[96012]: 165.154.138.151 res <anonymous>
Jul 27 05:54:53 twilightnode nnrpd[96012]: 165.154.138.151 no_match_user <anonymous> <localhost>
Jul 27 05:54:53 twilightnode nnrpd[96012]: 165.154.138.151 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 27 05:54:53 twilightnode nnrpd[96012]: 165.154.138.151 match_user <anonymous> <anonymous>
Jul 27 05:54:53 twilightnode nnrpd[96012]: 165.154.138.151 no_post anonymous-read
Jul 27 05:55:03 twilightnode nnrpd[96096]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 05:55:03 twilightnode nnrpd[96096]: Auth strategy 'localhost' does not match client. Removing.
Jul 27 05:55:03 twilightnode nnrpd[96096]: 165.154.138.151 res <anonymous>
Jul 27 05:55:03 twilightnode nnrpd[96096]: 165.154.138.151 no_match_user <anonymous> <localhost>
Jul 27 05:55:03 twilightnode nnrpd[96096]: 165.154.138.151 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 27 05:55:03 twilightnode nnrpd[96096]: 165.154.138.151 match_user <anonymous> <anonymous>
Jul 27 05:55:03 twilightnode nnrpd[96096]: 165.154.138.151 no_post anonymous-read
Jul 27 07:10:52 twilightnode nnrpd[99364]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 07:10:52 twilightnode nnrpd[99364]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 7088 71.9%1.0 MB 72.8%
innd 1321 13.4%197.4 KB 13.4%
innfeed 1200 12.2%178.4 KB 12.1%
nnrpd 246 2.5%25.2 KB 1.7%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 9856 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15540 47.8%
Negative hits 9944 30.6%
Do not exist 6986 21.5%
Cache misses 9 0.0%
TOTAL: 4 32479 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.018 0.0%5 1.000 3.600 4.667
article cleanup00:00:00.038 0.0%7008 0.000 0.005 0.062
article logging00:00:00.200 0.0%7068 0.000 0.028 0.125
article parse00:00:00.100 0.0%10001 0.000 0.010 0.056
article write00:00:04.569 0.0%5123 0.284 0.892 3.667
data move00:00:00.084 0.0%35142 0.000 0.002 0.023
hisgrep/artcncl00:00:00.007 0.0%5 0.000 1.400 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.586 0.0%32573 0.064 0.141 0.491
history sync00:00:00.033 0.0%351 0.000 0.094 1.000
history write00:00:03.400 0.0%7008 0.138 0.485 0.964
idle24:00:17.151100.0%34585 1056.560 2498.689 13956.349
nntp read00:00:00.562 0.0%34295 0.000 0.016 0.065
overview write00:00:02.025 0.0%5123 0.122 0.395 2.923
perl filter00:00:07.302 0.0%7008 0.537 1.042 12.438
python filter00:00:00.440 0.0%7008 0.000 0.063 1.312
site send00:00:00.085 0.0%5113 0.000 0.017 0.143
TOTAL: 1724:00:40.600100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.005 0.0%5512 0.000 0.001 0.071
article prepare00:00:00.001 0.0%18340 0.000 0.000 0.005
article read00:00:00.513 0.0%3560 0.000 0.144 1.600
backlog stats00:00:00.030 0.0%55127 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.348 0.0%30175 0.000 0.012 0.045
data write00:00:00.646 0.0%25025 0.000 0.026 0.160
idle23:52:09.420100.0%55127 341.276 1558.754 4360.752
status file00:00:00.115 0.0%213 0.000 0.540 7.000
TOTAL: 923:52:11.078100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:41.861100.0%30 34.500 1395.367 10053.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%145 0.000 0.000 0.000
TOTAL: 300:00:41.861100.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 11685 4375 6093 1217 37%47:01:37
2newsfeed.endofthelinebbs.com 2 5025 451 4015 559 8%22:20:11
3news.tebibyte.org 2 4970 80 4864 26 1%23:59:20
4news.sklaffkom.se 28 63 16 24 23 25%06:45:35
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 61 21743 4922 14996 1825 22%100:06:43
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.7 MB0.0 KB4.2 MB16.9 MB 75%3.1 KB
2newsfeed.endofthelinebbs.com4.2 MB0.0 KB1.5 MB5.7 MB 73%5.8 KB
3news.tebibyte.org252.4 KB0.0 KB139.8 KB392.3 KB 64%3.7 KB
4news.sklaffkom.se68.4 KB0.0 KB53.6 KB122.0 KB 56%3.1 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 517.2 MB0.0 KB5.9 MB23.2 MB 74%3.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 27 03:00:06 - 03:59:59 130 2.5% 0.04548.7 KB 2.9% 0.15
Jul 27 04:00:00 - 04:59:59 137 2.7% 0.04711.6 KB 3.8% 0.20
Jul 27 05:00:00 - 05:59:59 90 1.8% 0.03368.5 KB 2.0% 0.10
Jul 27 06:00:00 - 06:59:59 101 2.0% 0.03383.4 KB 2.1% 0.11
Jul 27 07:00:00 - 07:59:59 148 2.9% 0.04407.4 KB 2.2% 0.11
Jul 27 08:00:00 - 08:59:59 220 4.3% 0.06593.2 KB 3.2% 0.16
Jul 27 09:00:00 - 09:59:59 311 6.1% 0.09948.8 KB 5.1% 0.26
Jul 27 10:00:00 - 10:59:59 295 5.7% 0.08966.4 KB 5.2% 0.27
Jul 27 11:00:00 - 11:59:59 272 5.3% 0.081.0 MB 5.5% 0.29
Jul 27 12:00:00 - 12:59:59 294 5.7% 0.081.1 MB 6.1% 0.32
Jul 27 13:00:00 - 13:59:59 241 4.7% 0.07697.1 KB 3.7% 0.19
Jul 27 14:00:00 - 14:59:59 222 4.3% 0.06718.2 KB 3.9% 0.20
Jul 27 15:00:00 - 15:59:59 410 8.0% 0.111.5 MB 8.3% 0.43
Jul 27 16:00:00 - 16:59:59 300 5.8% 0.08983.6 KB 5.3% 0.27
Jul 27 17:00:00 - 17:59:59 261 5.1% 0.071.2 MB 6.5% 0.34
Jul 27 18:00:00 - 18:59:59 296 5.8% 0.081.1 MB 6.2% 0.32
Jul 27 19:00:00 - 19:59:59 261 5.1% 0.07884.3 KB 4.7% 0.25
Jul 27 20:00:00 - 20:59:59 216 4.2% 0.06945.4 KB 5.1% 0.26
Jul 27 21:00:00 - 21:59:59 214 4.2% 0.06671.4 KB 3.6% 0.19
Jul 27 22:00:00 - 22:59:59 200 3.9% 0.06697.8 KB 3.7% 0.19
Jul 27 23:00:00 - 23:59:59 133 2.6% 0.04407.4 KB 2.2% 0.11
Jul 28 00:00:00 - 00:59:59 149 2.9% 0.04549.9 KB 3.0% 0.15
Jul 28 01:00:00 - 01:59:59 122 2.4% 0.03496.2 KB 2.7% 0.14
Jul 28 02:00:00 - 02:59:59 104 2.0% 0.03554.5 KB 3.0% 0.15
Jul 28 03:00:00 - 03:00:05 9 0.2% 1.808.5 KB 0.0% 1.69
TOTAL: 23:59:59 5136 100.0% 0.0618.2 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1308 1132 0 0 0 0 0 0 176
2newsfeed.endofthelinebbs.com 577 97 0 0 0 0 0 0 480
3news.tebibyte.org 44 41 0 0 0 0 0 0 3
4news.sklaffkom.se 23 23 0 0 0 0 0 0 0
TOTAL: 4 1952 1293 0 0 0 0 0 0 659

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.slack 76
alt.checkmate 48
linux.debian.vote 48
alt.food.fast-food 47
pl.misc.elektronika 47
it.tlc.cellulari.android 43
fido7.ukrnews 39
lada.talk 33
alt.usage.english 28
de.alt.folklore.computer 27
linux.debian.user 26
de.alt.talk.unmut 24
pl.soc.prawo 24
hfx.general 23
pl.soc.polityka 22
alt.prophecies.nostradamus 19
cn.comp 19
eternal-september.talk 19
uk.religion.christian 17
uk.tech.digital-tv 16
TOTAL: 201 1293

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 476
EMP (phl) 69
User-issued cancel 47
Too many newsgroups (meow) 6
HTML Multipart 1
TOTAL: 5 599

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 5328 1074 1622 2432 0 35 20%23:59:55
2news.tebibyte.org 7035 1069 4056 3 0 58 15%23:59:55
3newsfeed.endofthelinebbs.com 8750 24 4591 11 0 319 0%23:59:46
4weretis.net 651 0 644 0 0 0 0%22:30:52
TOTAL: 4 21764 2167 10913 2446 0 412 9%94:30:28
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.2 MB460.1 KB3.6 MB0.0 KB/s3.5 KB23:59:55
2sklaffkom.se2.1 MB10.6 MB12.7 MB0.2 KB/s3.7 KB23:59:55
3newsfeed.endofthelinebbs.com78.8 KB157.5 KB236.3 KB0.0 KB/s6.8 KB23:59:46
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s022:30:52
TOTAL: 45.4 MB11.2 MB16.6 MB0.0 KB/s3.7 KB94:30:28
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1150.154.45.199.censys-scanner.com 5 00.0 KB 0 0 000:00:09
2165.154.138.151 3 00.0 KB 0 0 000:00:10
38.219.240.172 3 00.0 KB 0 0 000:00:15
4oslo.scan.bufferover.run 2 00.0 KB 0 0 000:00:00
5161.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
6221.13.93.234 1 00.0 KB 0 0 000:00:00
731.14.254.69 1 00.0 KB 0 0 000:00:00
869.5.169.221 1 00.0 KB 0 0 000:00:00
9o047.scanner.modat.io 1 00.0 KB 0 0 000:00:00
10r5-208-d0.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
11vmd129036.contaboserver.net 1 00.0 KB 0 0 000:00:00
TOTAL: 11 20 00.0 KB 0 0 000:00:41

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 9 00.0 KB 0 0 000:00:27
2*.199.censys-scanner.com 5 00.0 KB 0 0 000:00:09
3*.scan.bufferover.run 2 00.0 KB 0 0 000:00:00
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
5*.contaboserver.net 1 00.0 KB 0 0 000:00:00
6*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
7*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 20 00.0 KB 0 0 000:00:41

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
8.219.240.172 0.009 0.024 0.00000:00:15
165.154.138.151 0.016 0.013 0.00000:00:10
150.154.45.199.censys-scanner.com 0.045 0.035 0.00000:00:09
r5-208-d0.monitoring.internet-measurement.com 0.001 0.012 0.00000:00:01
161.186.132.66.censys-scanner.com 0.028 0.014 0.00000:00:00
vmd129036.contaboserver.net 0.005 0.018 0.00000:00:00
221.13.93.234 0.005 0.011 0.00000:00:00
oslo.scan.bufferover.run 0.004 0.021 0.00000:00:00
o047.scanner.modat.io 0.006 0.012 0.00000:00:00
31.14.254.69 0.004 0.004 0.00000:00:00
69.5.169.221 0.003 0.009 0.00000:00:00
TOTAL: 11 0.126 0.173 0.00000:00:41

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

SystemConn
oslo.scan.bufferover.run 8
8.219.240.172 3
150.154.45.199.censys-scanner.com 2
165.154.138.151 2
161.186.132.66.censys-scanner.com 1
TOTAL: 5 16

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

CommandCount
?#024 4
#026#003#001 3
#001 2
#026#003#001#001 2
?$?(?#?' 2
EHLO www.censys.io 2
?#?' 1
TOTAL: 7 16

NNRP client timeouts [Top 20]:

SystemConnPeer
150.154.45.199.censys-scanner.com 1 3
31.14.254.69 1 1
TOTAL: 2 2 5
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