Daily Usenet report

Jul 12 03:00:04 -- Jul 13 03:00:05

Unknown entries from news log file:

First 50 / 16699 lines (0.3%)

Jul 12 03:00:30 twilightnode nnrpd[11063]: Reading access from /usr/local/news/etc/readers.conf
Jul 12 03:00:30 twilightnode nnrpd[11063]: Auth strategy 'localhost' does not match client. Removing.
Jul 12 03:00:30 twilightnode nnrpd[11063]: 81.2.118.99 res <anonymous>
Jul 12 03:00:30 twilightnode nnrpd[11063]: 81.2.118.99 no_match_user <anonymous> <localhost>
Jul 12 03:00:30 twilightnode nnrpd[11063]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 12 03:00:30 twilightnode nnrpd[11063]: 81.2.118.99 match_user <anonymous> <anonymous>
Jul 12 03:00:30 twilightnode nnrpd[11063]: 81.2.118.99 no_post anonymous-read
Jul 12 03:00:30 twilightnode nnrpd[11063]: 81.2.118.99 noperm ihave without permission
Jul 12 03:01:10 twilightnode nnrpd[11083]: Reading access from /usr/local/news/etc/readers.conf
Jul 12 03:01:10 twilightnode nnrpd[11083]: Auth strategy 'localhost' does not match client. Removing.
Jul 12 03:01:10 twilightnode nnrpd[11083]: 81.2.118.99 res <anonymous>
Jul 12 03:01:10 twilightnode nnrpd[11083]: 81.2.118.99 no_match_user <anonymous> <localhost>
Jul 12 03:01:10 twilightnode nnrpd[11083]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 12 03:01:10 twilightnode nnrpd[11083]: 81.2.118.99 match_user <anonymous> <anonymous>
Jul 12 03:01:10 twilightnode nnrpd[11083]: 81.2.118.99 no_post anonymous-read
Jul 12 03:01:10 twilightnode nnrpd[11083]: 81.2.118.99 noperm ihave without permission
Jul 12 03:01:15 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 12 03:01:50 twilightnode nnrpd[11279]: Reading access from /usr/local/news/etc/readers.conf
Jul 12 03:01:50 twilightnode nnrpd[11279]: Auth strategy 'localhost' does not match client. Removing.
Jul 12 03:01:50 twilightnode nnrpd[11279]: 81.2.118.99 res <anonymous>
Jul 12 03:01:50 twilightnode nnrpd[11279]: 81.2.118.99 no_match_user <anonymous> <localhost>
Jul 12 03:01:50 twilightnode nnrpd[11279]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 12 03:01:50 twilightnode nnrpd[11279]: 81.2.118.99 match_user <anonymous> <anonymous>
Jul 12 03:01:50 twilightnode nnrpd[11279]: 81.2.118.99 no_post anonymous-read
Jul 12 03:01:50 twilightnode nnrpd[11279]: 81.2.118.99 noperm ihave without permission
Jul 12 03:02:30 twilightnode nnrpd[11286]: Reading access from /usr/local/news/etc/readers.conf
Jul 12 03:02:30 twilightnode nnrpd[11286]: Auth strategy 'localhost' does not match client. Removing.
Jul 12 03:02:30 twilightnode nnrpd[11286]: 81.2.118.99 res <anonymous>
Jul 12 03:02:30 twilightnode nnrpd[11286]: 81.2.118.99 no_match_user <anonymous> <localhost>
Jul 12 03:02:30 twilightnode nnrpd[11286]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 12 03:02:30 twilightnode nnrpd[11286]: 81.2.118.99 match_user <anonymous> <anonymous>
Jul 12 03:02:30 twilightnode nnrpd[11286]: 81.2.118.99 no_post anonymous-read
Jul 12 03:02:30 twilightnode nnrpd[11286]: 81.2.118.99 noperm ihave without permission
Jul 12 03:03:10 twilightnode nnrpd[11287]: Reading access from /usr/local/news/etc/readers.conf
Jul 12 03:03:10 twilightnode nnrpd[11287]: Auth strategy 'localhost' does not match client. Removing.
Jul 12 03:03:10 twilightnode nnrpd[11287]: 81.2.118.99 res <anonymous>
Jul 12 03:03:10 twilightnode nnrpd[11287]: 81.2.118.99 no_match_user <anonymous> <localhost>
Jul 12 03:03:10 twilightnode nnrpd[11287]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 12 03:03:10 twilightnode nnrpd[11287]: 81.2.118.99 match_user <anonymous> <anonymous>
Jul 12 03:03:10 twilightnode nnrpd[11287]: 81.2.118.99 no_post anonymous-read
Jul 12 03:03:10 twilightnode nnrpd[11287]: 81.2.118.99 noperm ihave without permission
Jul 12 03:03:50 twilightnode nnrpd[11291]: Reading access from /usr/local/news/etc/readers.conf
Jul 12 03:03:50 twilightnode nnrpd[11291]: Auth strategy 'localhost' does not match client. Removing.
Jul 12 03:03:50 twilightnode nnrpd[11291]: 81.2.118.99 res <anonymous>
Jul 12 03:03:50 twilightnode nnrpd[11291]: 81.2.118.99 no_match_user <anonymous> <localhost>
Jul 12 03:03:50 twilightnode nnrpd[11291]: 81.2.118.99 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 12 03:03:50 twilightnode nnrpd[11291]: 81.2.118.99 match_user <anonymous> <anonymous>
Jul 12 03:03:50 twilightnode nnrpd[11291]: 81.2.118.99 no_post anonymous-read
Jul 12 03:03:50 twilightnode nnrpd[11291]: 81.2.118.99 noperm ihave without permission
Jul 12 03:04:31 twilightnode nnrpd[11300]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 25060 73.3%2.4 MB 65.6%
inn 6379 18.7%966.5 KB 25.9%
innfeed 1414 4.1%151.3 KB 4.1%
innd 1323 3.9%167.3 KB 4.5%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 34177 100.0%3.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 9564 40.8%
Negative hits 7571 32.3%
Do not exist 6328 27.0%
Cache misses 1 0.0%
TOTAL: 4 23464 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.000 0.0%1 0.000 0.000 0.000
article cleanup00:00:00.304 0.0%6344 0.000 0.048 5.500
article logging00:00:01.132 0.0%6366 0.000 0.178 19.250
article parse00:00:00.457 0.0%8780 0.000 0.052 7.702
article write00:00:05.024 0.0%4613 0.385 1.089 32.000
data move00:00:00.157 0.0%25730 0.000 0.006 0.380
hisgrep/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.960 0.0%23510 0.091 0.211 4.825
history sync00:00:00.057 0.0%405 0.000 0.141 3.500
history write00:00:04.064 0.0%6342 0.176 0.641 24.045
idle23:49:15.320 99.9%29549 1163.122 2902.139 8460.732
nntp read00:00:00.629 0.0%25088 0.000 0.025 0.398
overview write00:00:02.756 0.0%4613 0.154 0.597 24.949
perl filter00:00:58.764 0.1%6342 0.849 9.266 1106.250
python filter00:00:08.900 0.0%6342 0.000 1.403 184.386
site send00:00:00.255 0.0%4607 0.000 0.055 4.128
TOTAL: 1723:50:42.779100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.155 0.0%5719 0.000 0.027 2.355
article prepare00:00:00.000 0.0%8052 0.000 0.000 0.000
article read00:00:00.517 0.0%2004 0.000 0.258 2.000
backlog stats00:00:00.192 0.0%37153 0.000 0.005 0.380
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.590 0.0%18724 0.000 0.032 1.281
data write00:00:00.459 0.0%14040 0.000 0.033 0.594
idle23:55:10.688 99.7%37153 458.147 2317.732 8228.082
status file00:00:00.155 0.0%328 0.000 0.473 2.000
TOTAL: 923:55:12.756 99.7%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:50:42.194100.0%6246 36.333 3368.907 10674.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.248 0.0%12514 0.000 0.020 6.000
TOTAL: 305:50:42.442100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 11144 4357 5593 1194 39%46:58:24
2newsfeed.endofthelinebbs.com 2 3895 228 3219 448 5%20:15:43
3news.sklaffkom.se 32 108 23 49 36 21%08:38:05
4localhost 25 0 0 0 0 0%00:00:00
TOTAL: 4 63 15147 4608 8861 1678 30%75:52:12
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net14.0 MB13.7 KB3.4 MB17.4 MB 80%3.2 KB
2newsfeed.endofthelinebbs.com3.0 MB0.0 KB1.2 MB4.2 MB 71%6.3 KB
3news.sklaffkom.se49.0 KB0.0 KB85.3 KB134.4 KB 36%2.3 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 417.0 MB13.7 KB4.6 MB21.7 MB 78%3.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 12 03:00:04 - 03:59:59 95 2.1% 0.03898.5 KB 5.1% 0.25
Jul 12 04:00:00 - 04:59:59 96 2.1% 0.03355.7 KB 2.0% 0.10
Jul 12 05:00:00 - 05:59:59 116 2.5% 0.03367.1 KB 2.1% 0.10
Jul 12 06:00:00 - 06:59:59 170 3.7% 0.05702.4 KB 4.0% 0.20
Jul 12 07:00:00 - 07:59:59 132 2.9% 0.041.7 MB 9.7% 0.47
Jul 12 08:00:00 - 08:59:59 171 3.7% 0.05523.5 KB 3.0% 0.15
Jul 12 09:00:00 - 09:59:59 218 4.7% 0.06492.1 KB 2.8% 0.14
Jul 12 10:00:00 - 10:59:59 242 5.2% 0.07638.2 KB 3.6% 0.18
Jul 12 11:00:00 - 11:59:59 249 5.4% 0.07629.5 KB 3.6% 0.17
Jul 12 12:00:00 - 12:59:59 221 4.8% 0.06606.8 KB 3.5% 0.17
Jul 12 13:00:00 - 13:59:59 234 5.1% 0.07622.4 KB 3.6% 0.17
Jul 12 14:00:00 - 14:59:59 196 4.2% 0.05437.4 KB 2.5% 0.12
Jul 12 15:00:00 - 15:59:59 256 5.5% 0.07808.1 KB 4.6% 0.22
Jul 12 16:00:00 - 16:59:59 228 4.9% 0.06656.0 KB 3.7% 0.18
Jul 12 17:00:00 - 17:59:59 243 5.3% 0.071.4 MB 8.0% 0.39
Jul 12 18:00:00 - 18:59:59 202 4.4% 0.06607.8 KB 3.5% 0.17
Jul 12 19:00:00 - 19:59:59 222 4.8% 0.06625.1 KB 3.6% 0.17
Jul 12 20:00:00 - 20:59:59 264 5.7% 0.071011.6 KB 5.8% 0.28
Jul 12 21:00:00 - 21:59:59 253 5.5% 0.071.3 MB 7.4% 0.36
Jul 12 22:00:00 - 22:59:59 284 6.1% 0.08873.9 KB 5.0% 0.24
Jul 12 23:00:00 - 23:59:59 166 3.6% 0.05851.5 KB 4.9% 0.24
Jul 13 00:00:00 - 00:59:59 128 2.8% 0.04485.6 KB 2.8% 0.13
Jul 13 01:00:00 - 01:59:59 138 3.0% 0.04580.4 KB 3.3% 0.16
Jul 13 02:00:00 - 02:59:59 90 1.9% 0.03328.5 KB 1.9% 0.09
Jul 13 03:00:00 - 03:00:05 10 0.2% 2.0011.8 KB 0.1% 2.37
TOTAL: 24:00:01 4624 100.0% 0.0517.1 MB 100.0% 0.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1215 972 0 2 0 0 0 0 241
2newsfeed.endofthelinebbs.com 504 128 0 0 0 0 0 0 376
3news.sklaffkom.se 36 36 0 0 0 0 0 0 0
TOTAL: 3 1755 1136 0 2 0 0 0 0 617

Unwanted newsgroups [Top 20]:

NewsgroupCount
pl.pregierz 56
alt.usage.english 51
pl.soc.prawo 44
eternal-september.test 38
de.comp.hardware.netzwerke.misc 37
de.etc.finanz.banken+broker 36
fido7.ukrnews 30
fido7.ru.fidonet.today 28
fido7.su.general 26
fido7.su.hardw.other 23
pl.misc.samochody 20
uk.legal.moderated 20
dk.politik 19
pl.misc.elektronika 19
fido7.altyn.local 18
it-alt.sport.calcio.juventus 18
cn.comp 15
free.it.ai.general 15
hfx.general 14
can.politics 13
TOTAL: 211 1136

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 345
EMP (phl) 182
User-issued cancel 30
Too many newsgroups (meow) 23
HTML Multipart 11
EMP (phn path) 3
HTML post 1
TOTAL: 7 595

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 5310 966 2630 1023 0 951 18%23:59:45
2newsfeed.endofthelinebbs.com 7950 12 4338 20 0 153 0%23:57:43
3weretis.net 277 0 275 0 0 1 0%17:14:15
4news.tebibyte.org 0 0 0 0 0 4618 0%24:00:00
TOTAL: 4 13537 978 7243 1043 0 5723 7%89:11:43
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1sklaffkom.se1.5 MB5.0 MB6.5 MB0.1 KB/s3.4 KB23:59:45
2newsfeed.endofthelinebbs.com47.7 KB48.4 KB96.1 KB0.0 KB/s3.0 KB23:57:43
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s017:14:15
TOTAL: 41.6 MB5.1 MB6.6 MB0.0 KB/s3.4 KB89:11:43
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
181.2.118.992077 00.0 KB 0 0 005:49:57
28.222.182.210 3 00.0 KB 0 0 000:00:15
3azpdcs4e1y38.stretchoid.com 2 00.0 KB 0 0 000:00:10
4azpdes5sthqq.stretchoid.com 2 00.0 KB 0 0 000:00:10
5193.176.31.195 1 00.0 KB 0 0 000:00:00
6194.50.235.153 1 00.0 KB 0 0 000:00:00
7195-252-55-9-no600.tbcn.telia.com 1 00.0 KB 0 0 000:00:03
890.224.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
9o304.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 92089 00.0 KB 0 0 005:50:42

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved2082 00.0 KB 0 0 005:50:13
2*.stretchoid.com 4 00.0 KB 0 0 000:00:21
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
5*.tbcn.telia.com 1 00.0 KB 0 0 000:00:03
TOTAL: 52089 00.0 KB 0 0 005:50:42

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
81.2.118.99 7.515 12.332 0.00005:49:57
8.222.182.210 0.011 0.012 0.00000:00:15
azpdcs4e1y38.stretchoid.com 0.009 0.012 0.00000:00:10
azpdes5sthqq.stretchoid.com 0.005 0.020 0.00000:00:10
195-252-55-9-no600.tbcn.telia.com 0.006 0.005 0.00000:00:03
90.224.132.66.censys-scanner.com 0.026 0.009 0.00000:00:03
194.50.235.153 0.003 0.006 0.00000:00:00
193.176.31.195 0.003 0.006 0.00000:00:00
o304.scanner.modat.io 0.001 0.006 0.00000:00:00
TOTAL: 9 7.579 12.408 0.00005:50:42

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

SystemConn
8.222.182.210 4
90.224.132.66.censys-scanner.com 1
azpdcs4e1y38.stretchoid.com 1
azpdes5sthqq.stretchoid.com 1
TOTAL: 4 7

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

CommandCount
MGLNDD_172.234.115.63_119 2
#026#003#001 1
?#024 1
?#?' 1
EHLO www.censys.io 1
w?ks. 1
TOTAL: 6 7

NNRP client timeouts [Top 20]:

SystemConnPeer
194.50.235.153 1 1
TOTAL: 1 1 2
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