Daily Usenet report

Jul 26 03:00:03 -- Jul 27 03:00:05

Unknown entries from news log file:

First 50 / 62 lines (80.6%)

Jul 26 03:01:15 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 26 03:24:30 twilightnode nnrpd[25696]: Reading access from /usr/local/news/etc/readers.conf
Jul 26 03:24:30 twilightnode nnrpd[25696]: Auth strategy 'localhost' does not match client. Removing.
Jul 26 03:24:30 twilightnode nnrpd[25696]: 59.146.94.167.censys-scanner.com res <anonymous>
Jul 26 03:24:30 twilightnode nnrpd[25696]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 26 03:24:30 twilightnode nnrpd[25696]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 26 03:24:30 twilightnode nnrpd[25696]: 59.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jul 26 03:24:30 twilightnode nnrpd[25696]: 59.146.94.167.censys-scanner.com no_post anonymous-read
Jul 26 03:24:33 twilightnode nnrpd[25697]: Reading access from /usr/local/news/etc/readers.conf
Jul 26 03:24:33 twilightnode nnrpd[25697]: Auth strategy 'localhost' does not match client. Removing.
Jul 26 03:24:33 twilightnode nnrpd[25697]: 59.146.94.167.censys-scanner.com res <anonymous>
Jul 26 03:24:33 twilightnode nnrpd[25697]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 26 03:24:33 twilightnode nnrpd[25697]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 26 03:24:33 twilightnode nnrpd[25697]: 59.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jul 26 03:24:33 twilightnode nnrpd[25697]: 59.146.94.167.censys-scanner.com no_post anonymous-read
Jul 26 03:24:36 twilightnode nnrpd[25698]: Reading access from /usr/local/news/etc/readers.conf
Jul 26 03:24:36 twilightnode nnrpd[25698]: Auth strategy 'localhost' does not match client. Removing.
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com res <anonymous>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com no_post anonymous-read
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com res <anonymous>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jul 26 03:24:36 twilightnode nnrpd[25698]: 59.146.94.167.censys-scanner.com no_post anonymous-read
Jul 26 11:37:32 twilightnode nnrpd[46881]: Reading access from /usr/local/news/etc/readers.conf
Jul 26 11:37:32 twilightnode nnrpd[46881]: Auth strategy 'localhost' does not match client. Removing.
Jul 26 11:37:32 twilightnode nnrpd[46881]: mcintosh.probe.onyphe.net res <anonymous>
Jul 26 11:37:32 twilightnode nnrpd[46881]: mcintosh.probe.onyphe.net no_match_user <anonymous> <localhost>
Jul 26 11:37:32 twilightnode nnrpd[46881]: mcintosh.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 26 11:37:32 twilightnode nnrpd[46881]: mcintosh.probe.onyphe.net match_user <anonymous> <anonymous>
Jul 26 11:37:32 twilightnode nnrpd[46881]: mcintosh.probe.onyphe.net no_post anonymous-read
Jul 26 11:37:32 twilightnode nnrpd[46882]: Reading access from /usr/local/news/etc/readers.conf
Jul 26 11:37:32 twilightnode nnrpd[46882]: Auth strategy 'localhost' does not match client. Removing.
Jul 26 11:37:32 twilightnode nnrpd[46882]: francis.probe.onyphe.net res <anonymous>
Jul 26 11:37:32 twilightnode nnrpd[46882]: francis.probe.onyphe.net no_match_user <anonymous> <localhost>
Jul 26 11:37:32 twilightnode nnrpd[46882]: francis.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 26 11:37:32 twilightnode nnrpd[46882]: francis.probe.onyphe.net match_user <anonymous> <anonymous>
Jul 26 11:37:32 twilightnode nnrpd[46882]: francis.probe.onyphe.net no_post anonymous-read
Jul 26 18:08:50 twilightnode nnrpd[63219]: Reading access from /usr/local/news/etc/readers.conf
Jul 26 18:08:50 twilightnode nnrpd[63219]: Auth strategy 'localhost' does not match client. Removing.
Jul 26 18:08:50 twilightnode nnrpd[63219]: 205.210.31.199 res <anonymous>
Jul 26 18:08:50 twilightnode nnrpd[63219]: 205.210.31.199 no_match_user <anonymous> <localhost>
Jul 26 18:08:50 twilightnode nnrpd[63219]: 205.210.31.199 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 26 18:08:50 twilightnode nnrpd[63219]: 205.210.31.199 match_user <anonymous> <anonymous>
Jul 26 18:08:50 twilightnode nnrpd[63219]: 205.210.31.199 no_post anonymous-read
Jul 27 02:15:36 twilightnode nnrpd[86781]: Reading access from /usr/local/news/etc/readers.conf
Jul 27 02:15:36 twilightnode nnrpd[86781]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6251 68.0%939.4 KB 69.2%
innd 1513 16.5%217.2 KB 16.0%
innfeed 1333 14.5%190.7 KB 14.0%
nnrpd 97 1.1%10.2 KB 0.8%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 9195 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14174 48.4%
Negative hits 8914 30.5%
Do not exist 6181 21.1%
Cache misses 1 0.0%
TOTAL: 4 29270 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.031 0.0%6193 0.000 0.005 0.080
article logging00:00:00.187 0.0%6236 0.000 0.030 0.167
article parse00:00:00.096 0.0%7911 0.000 0.012 0.091
article write00:00:04.170 0.0%4435 0.364 0.940 5.632
data move00:00:00.080 0.0%30865 0.000 0.003 0.026
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:03.977 0.0%29327 0.064 0.136 0.331
history sync00:00:00.043 0.0%404 0.000 0.106 0.667
history write00:00:02.953 0.0%6193 0.214 0.477 1.019
idle23:50:41.877100.0%30939 1435.380 2774.552 10725.518
nntp read00:00:00.568 0.0%30595 0.000 0.019 0.050
overview write00:00:01.749 0.0%4435 0.121 0.394 1.250
perl filter00:00:06.891 0.0%6193 0.566 1.113 14.480
python filter00:00:00.388 0.0%6193 0.000 0.063 1.360
site send00:00:00.071 0.0%4428 0.000 0.016 0.211
TOTAL: 1723:51:03.081100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.006 0.0%4507 0.000 0.001 0.043
article prepare00:00:00.000 0.0%15744 0.000 0.000 0.000
article read00:00:00.477 0.0%2865 0.000 0.166 1.625
backlog stats00:00:00.037 0.0%50010 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.395 0.0%26800 0.000 0.015 0.044
data write00:00:00.567 0.0%22232 0.000 0.026 0.073
idle23:50:47.499100.0%50010 467.953 1716.607 5265.649
status file00:00:00.128 0.0%280 0.000 0.457 2.000
TOTAL: 923:50:49.109100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:16.112100.0%13 48.333 1239.385 10466.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%74 0.000 0.014 0.200
TOTAL: 300:00:16.113100.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 10220 4034 5086 1100 39%44:33:45
2newsfeed.endofthelinebbs.com 2 4563 234 3773 556 5%24:09:08
3news.sklaffkom.se 38 75 33 9 33 44%09:04:09
4news.tebibyte.org 1 974 18 939 17 1%09:05:05
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 70 15832 4319 9807 1706 27%86:52:07
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.5 MB0.0 KB3.1 MB14.6 MB 78%2.9 KB
2newsfeed.endofthelinebbs.com1.7 MB0.0 KB1.7 MB3.4 MB 49%4.4 KB
3news.sklaffkom.se132.6 KB0.0 KB76.8 KB209.3 KB 63%3.2 KB
4news.tebibyte.org62.8 KB0.0 KB46.2 KB109.0 KB 57%3.1 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 513.4 MB0.0 KB4.9 MB18.3 MB 73%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 26 03:00:03 - 03:59:59 75 1.7% 0.02222.8 KB 1.6% 0.06
Jul 26 04:00:00 - 04:59:59 91 2.0% 0.03273.4 KB 2.0% 0.08
Jul 26 05:00:00 - 05:59:59 104 2.3% 0.03308.0 KB 2.2% 0.09
Jul 26 06:00:00 - 06:59:59 89 2.0% 0.02183.8 KB 1.3% 0.05
Jul 26 07:00:00 - 07:59:59 143 3.2% 0.04561.1 KB 4.0% 0.16
Jul 26 08:00:00 - 08:59:59 190 4.3% 0.05677.3 KB 4.8% 0.19
Jul 26 09:00:00 - 09:59:59 175 3.9% 0.05484.1 KB 3.5% 0.13
Jul 26 10:00:00 - 10:59:59 205 4.6% 0.06478.9 KB 3.4% 0.13
Jul 26 11:00:00 - 11:59:59 260 5.8% 0.07837.9 KB 6.0% 0.23
Jul 26 12:00:00 - 12:59:59 227 5.1% 0.06704.6 KB 5.0% 0.20
Jul 26 13:00:00 - 13:59:59 233 5.2% 0.06681.5 KB 4.9% 0.19
Jul 26 14:00:00 - 14:59:59 183 4.1% 0.05583.9 KB 4.2% 0.16
Jul 26 15:00:00 - 15:59:59 262 5.9% 0.07765.0 KB 5.5% 0.21
Jul 26 16:00:00 - 16:59:59 253 5.7% 0.07685.1 KB 4.9% 0.19
Jul 26 17:00:00 - 17:59:59 221 5.0% 0.06723.7 KB 5.2% 0.20
Jul 26 18:00:00 - 18:59:59 289 6.5% 0.08804.5 KB 5.8% 0.22
Jul 26 19:00:00 - 19:59:59 225 5.1% 0.06530.7 KB 3.8% 0.15
Jul 26 20:00:00 - 20:59:59 251 5.6% 0.071.0 MB 7.3% 0.28
Jul 26 21:00:00 - 21:59:59 222 5.0% 0.06666.2 KB 4.8% 0.19
Jul 26 22:00:00 - 22:59:59 252 5.7% 0.071.0 MB 7.5% 0.29
Jul 26 23:00:00 - 23:59:59 143 3.2% 0.04510.0 KB 3.6% 0.14
Jul 27 00:00:00 - 00:59:59 142 3.2% 0.04484.4 KB 3.5% 0.13
Jul 27 01:00:00 - 01:59:59 119 2.7% 0.03438.8 KB 3.1% 0.12
Jul 27 02:00:00 - 02:59:59 84 1.9% 0.02289.1 KB 2.1% 0.08
Jul 27 03:00:00 - 03:00:05 12 0.3% 2.4012.7 KB 0.1% 2.55
TOTAL: 24:00:02 4450 100.0% 0.0513.7 MB 100.0% 0.16
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1175 891 0 0 0 0 0 0 284
2newsfeed.endofthelinebbs.com 566 52 0 0 0 0 0 0 514
3news.sklaffkom.se 33 33 0 0 0 0 0 0 0
4news.tebibyte.org 27 20 0 0 0 0 0 0 7
TOTAL: 4 1801 996 0 0 0 0 0 0 805

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.slack 40
alt.checkmate 37
lada.talk 31
pl.soc.prawo 30
eternal-september.talk 26
hfx.general 24
pl.soc.polityka 24
de.alt.fan.pluesch 21
de.alt.talk.unmut 21
it.sport.formula1 21
de.talk.jokes 20
alt.usage.english 19
fido7.ukrnews 19
cn.comp 18
linux.debian.vote 18
dk.politik 17
fr.sci.maths 17
linux.debian.maint.boot 17
de.comp.hardware.misc 16
it.tlc.cellulari.android 16
TOTAL: 186 996

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 488
EMP (phl) 236
User-issued cancel 33
HTML Multipart 4
Too many newsgroups 1
TOTAL: 5 762

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6014 1085 3356 3 0 5 18%23:59:49
2sklaffkom.se 4592 982 1604 1855 0 2 21%24:00:00
3newsfeed.endofthelinebbs.com 8033 10 4160 15 0 80 0%23:57:57
4weretis.net 358 2 347 0 0 0 0%18:56:58
TOTAL: 4 18997 2079 9467 1873 0 87 10%90:54:44
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.1 MB196.3 KB3.3 MB0.0 KB/s3.1 KB23:59:49
2sklaffkom.se1.6 MB7.4 MB9.0 MB0.1 KB/s3.2 KB24:00:00
3newsfeed.endofthelinebbs.com27.6 KB43.1 KB70.7 KB0.0 KB/s2.8 KB23:57:57
4weretis.net2.5 KB0.0 KB2.5 KB0.0 KB/s1.3 KB18:56:58
TOTAL: 44.7 MB7.7 MB12.4 MB0.0 KB/s3.2 KB90:54:44
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
159.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:00
2azpdcsw2v3er.stretchoid.com 2 00.0 KB 0 0 000:00:10
3205.210.31.199 1 00.0 KB 0 0 000:00:01
4francis.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
5mcintosh.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
TOTAL: 5 8 00.0 KB 0 0 000:00:16

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:00
2*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4unresolved 1 00.0 KB 0 0 000:00:01
TOTAL: 4 8 00.0 KB 0 0 000:00:16

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcsw2v3er.stretchoid.com 0.004 0.015 0.00000:00:10
francis.probe.onyphe.net 0.004 0.003 0.00000:00:02
205.210.31.199 0.006 0.017 0.00000:00:01
59.146.94.167.censys-scanner.com 0.041 0.017 0.00000:00:00
mcintosh.probe.onyphe.net 0.003 0.010 0.00000:00:00
TOTAL: 5 0.058 0.062 0.00000:00:16

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

SystemConn
mcintosh.probe.onyphe.net 2
59.146.94.167.censys-scanner.com 1
azpdcsw2v3er.stretchoid.com 1
TOTAL: 3 4

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

CommandCount
#026#003#003#002c#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
59.146.94.167.censys-scanner.com 1 2
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