Daily Usenet report

Aug 22 03:00:03 -- Aug 23 03:00:03

Unknown entries from news log file:

First 50 / 11573 lines (0.4%)

Aug 22 03:00:59 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 22 03:02:00 twilightnode nnrpd[1891053]: Reading access from /usr/local/news/etc/readers.conf
Aug 22 03:02:00 twilightnode nnrpd[1891053]: Auth strategy 'localhost' does not match client. Removing.
Aug 22 03:02:00 twilightnode nnrpd[1891053]: usenet.blueworldhosting.com res <anonymous>
Aug 22 03:02:00 twilightnode nnrpd[1891053]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 22 03:02:00 twilightnode nnrpd[1891053]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 22 03:02:00 twilightnode nnrpd[1891053]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 22 03:02:00 twilightnode nnrpd[1891053]: usenet.blueworldhosting.com no_post anonymous-read
Aug 22 03:02:00 twilightnode nnrpd[1891054]: Reading access from /usr/local/news/etc/readers.conf
Aug 22 03:02:00 twilightnode nnrpd[1891054]: Auth strategy 'localhost' does not match client. Removing.
Aug 22 03:02:00 twilightnode nnrpd[1891054]: usenet.blueworldhosting.com res <anonymous>
Aug 22 03:02:00 twilightnode nnrpd[1891054]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 22 03:02:00 twilightnode nnrpd[1891054]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 22 03:02:00 twilightnode nnrpd[1891054]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 22 03:02:00 twilightnode nnrpd[1891054]: usenet.blueworldhosting.com no_post anonymous-read
Aug 22 03:02:00 twilightnode nnrpd[1891053]: usenet.blueworldhosting.com noperm ihave without permission
Aug 22 03:02:00 twilightnode nnrpd[1891054]: usenet.blueworldhosting.com noperm ihave without permission
Aug 22 03:04:00 twilightnode nnrpd[1891064]: Reading access from /usr/local/news/etc/readers.conf
Aug 22 03:04:00 twilightnode nnrpd[1891064]: Auth strategy 'localhost' does not match client. Removing.
Aug 22 03:04:00 twilightnode nnrpd[1891064]: usenet.blueworldhosting.com res <anonymous>
Aug 22 03:04:00 twilightnode nnrpd[1891064]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 22 03:04:00 twilightnode nnrpd[1891064]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 22 03:04:00 twilightnode nnrpd[1891064]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 22 03:04:00 twilightnode nnrpd[1891064]: usenet.blueworldhosting.com no_post anonymous-read
Aug 22 03:04:00 twilightnode nnrpd[1891065]: Reading access from /usr/local/news/etc/readers.conf
Aug 22 03:04:00 twilightnode nnrpd[1891065]: Auth strategy 'localhost' does not match client. Removing.
Aug 22 03:04:00 twilightnode nnrpd[1891065]: usenet.blueworldhosting.com res <anonymous>
Aug 22 03:04:00 twilightnode nnrpd[1891065]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 22 03:04:00 twilightnode nnrpd[1891065]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 22 03:04:00 twilightnode nnrpd[1891065]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 22 03:04:00 twilightnode nnrpd[1891065]: usenet.blueworldhosting.com no_post anonymous-read
Aug 22 03:04:00 twilightnode nnrpd[1891065]: usenet.blueworldhosting.com noperm ihave without permission
Aug 22 03:04:00 twilightnode nnrpd[1891064]: usenet.blueworldhosting.com noperm ihave without permission
Aug 22 03:06:00 twilightnode nnrpd[1891218]: Reading access from /usr/local/news/etc/readers.conf
Aug 22 03:06:00 twilightnode nnrpd[1891217]: Reading access from /usr/local/news/etc/readers.conf
Aug 22 03:06:00 twilightnode nnrpd[1891218]: Auth strategy 'localhost' does not match client. Removing.
Aug 22 03:06:00 twilightnode nnrpd[1891218]: usenet.blueworldhosting.com res <anonymous>
Aug 22 03:06:00 twilightnode nnrpd[1891218]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 22 03:06:00 twilightnode nnrpd[1891218]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 22 03:06:00 twilightnode nnrpd[1891218]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 22 03:06:00 twilightnode nnrpd[1891218]: usenet.blueworldhosting.com no_post anonymous-read
Aug 22 03:06:00 twilightnode nnrpd[1891217]: Auth strategy 'localhost' does not match client. Removing.
Aug 22 03:06:00 twilightnode nnrpd[1891217]: usenet.blueworldhosting.com res <anonymous>
Aug 22 03:06:00 twilightnode nnrpd[1891217]: usenet.blueworldhosting.com no_match_user <anonymous> <localhost>
Aug 22 03:06:00 twilightnode nnrpd[1891217]: usenet.blueworldhosting.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 22 03:06:00 twilightnode nnrpd[1891217]: usenet.blueworldhosting.com match_user <anonymous> <anonymous>
Aug 22 03:06:00 twilightnode nnrpd[1891217]: usenet.blueworldhosting.com no_post anonymous-read
Aug 22 03:06:00 twilightnode nnrpd[1891217]: usenet.blueworldhosting.com noperm ihave without permission
Aug 22 03:06:00 twilightnode nnrpd[1891218]: usenet.blueworldhosting.com noperm ihave without permission
Aug 22 03:08:00 twilightnode nnrpd[1891372]: Reading access from /usr/local/news/etc/readers.conf

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 15932 63.4%1.7 MB 55.0%
inn 6257 24.9%985.5 KB 32.0%
innd 1690 6.7%218.8 KB 7.1%
innfeed 1240 4.9%181.4 KB 5.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 25120 100.0%3.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12466 46.6%
Negative hits 8094 30.2%
Do not exist 6193 23.1%
Cache misses 6 0.0%
TOTAL: 4 26759 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.003 0.0%1 3.000 3.000 3.000
article cleanup00:00:00.063 0.0%6205 0.000 0.010 0.143
article logging00:00:00.196 0.0%6253 0.000 0.031 0.238
article parse00:00:00.075 0.0%7878 0.000 0.010 0.077
article write00:00:04.406 0.0%4528 0.520 0.973 1.917
data move00:00:00.093 0.0%28438 0.000 0.003 0.046
hisgrep/artcncl00:00:00.002 0.0%1 2.000 2.000 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.928 0.0%26807 0.070 0.147 0.582
history sync00:00:00.076 0.0%311 0.000 0.244 5.500
history write00:00:02.910 0.0%6205 0.192 0.469 1.133
idle23:59:55.507100.0%31062 1414.825 2781.389 9838.475
nntp read00:00:00.530 0.0%27973 0.000 0.019 0.052
overview write00:00:02.230 0.0%4528 0.167 0.492 2.000
perl filter00:00:12.522 0.0%6205 0.925 2.018 10.438
python filter00:00:00.779 0.0%6205 0.000 0.126 1.947
site send00:00:00.193 0.0%9044 0.000 0.021 0.091
TOTAL: 1724:00:23.513100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%4608 0.000 0.006 0.182
article prepare00:00:00.003 0.0%21948 0.000 0.000 0.007
article read00:00:00.620 0.0%3190 0.000 0.194 2.333
backlog stats00:00:00.032 0.0%51891 0.000 0.001 0.017
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.379 0.0%28092 0.000 0.013 0.059
data write00:00:00.670 0.0%23544 0.000 0.028 0.106
idle23:58:44.978100.0%51891 407.346 1663.583 5827.136
status file00:00:00.222 0.0%232 0.000 0.957 59.000
TOTAL: 923:58:46.932100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:48:28.382100.0%4329 59.000 671.837900688.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.184 0.0%10158 0.000 0.018 0.500
TOTAL: 300:48:28.566100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 5 10355 3896 5461 998 37%44:53:00
2newsfeed.endofthelinebbs.com 2 4396 264 3623 509 6%23:24:43
3news.tebibyte.org 2 926 114 713 99 12%08:54:19
4news.sklaffkom.se 43 94 44 24 26 46%10:20:12
5localhost 25 0 0 0 0 0%00:00:01
TOTAL: 5 77 15771 4318 9821 1632 27%87:32:15
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net10.8 MB0.0 KB3.6 MB14.3 MB 75%3.0 KB
2newsfeed.endofthelinebbs.com2.1 MB0.0 KB803.8 KB2.9 MB 72%3.8 KB
3news.tebibyte.org387.8 KB0.0 KB88.2 KB476.0 KB 81%2.2 KB
4news.sklaffkom.se118.3 KB0.0 KB61.2 KB179.5 KB 65%2.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 513.4 MB0.0 KB4.5 MB17.8 MB 74%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 22 03:00:03 - 03:59:59 82 1.8% 0.02257.8 KB 1.8% 0.07
Aug 22 04:00:00 - 04:59:59 80 1.8% 0.02232.7 KB 1.6% 0.06
Aug 22 05:00:00 - 05:59:59 67 1.5% 0.02283.3 KB 2.0% 0.08
Aug 22 06:00:00 - 06:59:59 109 2.4% 0.03491.1 KB 3.4% 0.14
Aug 22 07:00:00 - 07:59:59 124 2.7% 0.03370.7 KB 2.6% 0.10
Aug 22 08:00:00 - 08:59:59 110 2.4% 0.03281.8 KB 1.9% 0.08
Aug 22 09:00:00 - 09:59:59 173 3.8% 0.05419.0 KB 2.9% 0.12
Aug 22 10:00:00 - 10:59:59 223 4.9% 0.06703.5 KB 4.8% 0.20
Aug 22 11:00:00 - 11:59:59 218 4.8% 0.06602.7 KB 4.2% 0.17
Aug 22 12:00:00 - 12:59:59 287 6.3% 0.08771.0 KB 5.3% 0.21
Aug 22 13:00:00 - 13:59:59 221 4.9% 0.06643.0 KB 4.4% 0.18
Aug 22 14:00:00 - 14:59:59 211 4.7% 0.06664.8 KB 4.6% 0.18
Aug 22 15:00:00 - 15:59:59 238 5.3% 0.07578.4 KB 4.0% 0.16
Aug 22 16:00:00 - 16:59:59 223 4.9% 0.06667.0 KB 4.6% 0.19
Aug 22 17:00:00 - 17:59:59 265 5.8% 0.07869.2 KB 6.0% 0.24
Aug 22 18:00:00 - 18:59:59 243 5.4% 0.07711.8 KB 4.9% 0.20
Aug 22 19:00:00 - 19:59:59 239 5.3% 0.07698.8 KB 4.8% 0.19
Aug 22 20:00:00 - 20:59:59 231 5.1% 0.06735.6 KB 5.1% 0.20
Aug 22 21:00:00 - 21:59:59 265 5.8% 0.071.1 MB 7.8% 0.31
Aug 22 22:00:00 - 22:59:59 256 5.6% 0.07936.1 KB 6.4% 0.26
Aug 22 23:00:00 - 23:59:59 216 4.8% 0.06770.1 KB 5.3% 0.21
Aug 23 00:00:00 - 00:59:59 209 4.6% 0.06842.5 KB 5.8% 0.23
Aug 23 01:00:00 - 01:59:59 138 3.0% 0.04531.1 KB 3.7% 0.15
Aug 23 02:00:00 - 02:59:59 94 2.1% 0.03319.5 KB 2.2% 0.09
Aug 23 03:00:00 - 03:00:03 9 0.2% 3.007.5 KB 0.1% 2.49
TOTAL: 24:00:00 4531 100.0% 0.0514.2 MB 100.0% 0.17
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1111 820 0 0 0 0 0 0 291
2newsfeed.endofthelinebbs.com 498 58 0 0 0 0 0 0 440
3news.tebibyte.org 91 89 0 0 0 0 0 0 2
4news.sklaffkom.se 26 26 0 0 0 0 0 0 0
TOTAL: 4 1726 993 0 0 0 0 0 0 733

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.net.news.moderation 49
bln.test 32
alt.bestjobsusa.computer.jobs 31
alt.usage.english 30
pl.misc.telefonia.gsm 30
de.test 27
alt.fan.rush-limbaurgh 25
pl.soc.prawo 25
de.alt.fan.pluesch 24
alt.checkmate 23
hfx.general 23
lada.talk 22
linux.debian.user 22
muc.lists.freebsd.bugs 22
cn.comp 20
pl.sci.fizyka 19
dk.politik 18
alt.sex.pedophilia 17
free.it.ai.general 17
pl.soc.polityka 17
TOTAL: 163 993

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 444
EMP (phn path) 152
EMP (phn nph) 64
User-issued cancel 11
Too many newsgroups (meow) 7
HTML Multipart 3
Binary: misplaced binary 2
Too many newsgroups 2
TOTAL: 8 685

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 5532 2400 2117 2 0 1 43%23:59:57
2sklaffkom.se 4648 942 1456 2121 0 0 20%23:59:40
3newsfeed.endofthelinebbs.com 7984 26 4208 6 0 95 0%23:59:57
4weretis.net 508 2 473 0 0 0 0%21:33:10
TOTAL: 4 18672 3370 8254 2129 0 96 18%93:32:44
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org6.2 MB25.5 KB6.3 MB0.1 KB/s2.7 KB23:59:57
2sklaffkom.se1.5 MB7.9 MB9.4 MB0.1 KB/s3.1 KB23:59:40
3newsfeed.endofthelinebbs.com65.2 KB9.3 KB74.6 KB0.0 KB/s2.3 KB23:59:57
4weretis.net3.4 KB0.0 KB3.4 KB0.0 KB/s1.7 KB21:33:10
TOTAL: 47.8 MB7.9 MB15.8 MB0.0 KB/s2.9 KB93:32:44
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1usenet.blueworldhosting.com1438 00.0 KB 0 0 000:18:21
2164.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
3185-216-145-186.infrawat.ch 1 00.0 KB 0 0 000:00:00
4205.210.31.134 1 00.0 KB 0 0 000:00:01
5216.226.77.30 1 00.0 KB 0 0 000:00:00
636.156.22.2 1 00.0 KB 0 0 000:30:01
75-226-140-121.infrawat.ch 1 00.0 KB 0 0 000:00:00
862.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
969-5-169-45.infrawat.ch 1 00.0 KB 0 0 000:00:00
1089-37-172-132.infrawat.ch 1 00.0 KB 0 0 000:00:00
TOTAL: 101447 00.0 KB 0 0 000:48:28

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.blueworldhosting.com1438 00.0 KB 0 0 000:18:21
2*.infrawat.ch 4 00.0 KB 0 0 000:00:01
3unresolved 3 00.0 KB 0 0 000:30:03
4*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
5*.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
TOTAL: 51447 00.0 KB 0 0 000:48:28

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
36.156.22.2 0.003 0.008 0.00000:30:01
usenet.blueworldhosting.com 5.456 9.771 0.00000:18:21
62.195.132.66.censys-scanner.com 0.026 0.011 0.00000:00:01
205.210.31.134 0.002 0.007 0.00000:00:01
216.226.77.30 0.004 0.006 0.00000:00:00
69-5-169-45.infrawat.ch 0.005 0.008 0.00000:00:00
5-226-140-121.infrawat.ch 0.005 0.008 0.00000:00:00
185-216-145-186.infrawat.ch 0.003 0.009 0.00000:00:00
164.149.216.162.bc.googleusercontent.com 0.005 0.009 0.00000:00:00
89-37-172-132.infrawat.ch 0.007 0.008 0.00000:00:00
TOTAL: 10 5.516 9.845 0.00000:48:28

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

SystemConn
164.149.216.162.bc.googleusercontent.com 4
62.195.132.66.censys-scanner.com 1
TOTAL: 2 5

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

CommandCount
Accept-Encoding: gzip 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
User-Agent: Hello from Palo Alto Network... 1
TOTAL: 5 5

NNRP client timeouts [Top 20]:

SystemConnPeer
185-216-145-186.infrawat.ch 1 1
36.156.22.2 1 0
5-226-140-121.infrawat.ch 1 1
TOTAL: 3 3 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