Daily Usenet report

Aug 10 03:00:03 -- Aug 11 03:00:03

Unknown entries from news log file:

First 50 / 95 lines (52.6%)

Aug 10 03:00:55 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 10 04:53:34 twilightnode nnrpd[1032658]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 04:53:34 twilightnode nnrpd[1032658]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 04:53:34 twilightnode nnrpd[1032658]: azpdws6b5q5j.stretchoid.com res <anonymous>
Aug 10 04:53:34 twilightnode nnrpd[1032658]: azpdws6b5q5j.stretchoid.com no_match_user <anonymous> <localhost>
Aug 10 04:53:34 twilightnode nnrpd[1032658]: azpdws6b5q5j.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 04:53:34 twilightnode nnrpd[1032658]: azpdws6b5q5j.stretchoid.com match_user <anonymous> <anonymous>
Aug 10 04:53:34 twilightnode nnrpd[1032658]: azpdws6b5q5j.stretchoid.com no_post anonymous-read
Aug 10 04:53:45 twilightnode nnrpd[1032659]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 04:53:45 twilightnode nnrpd[1032659]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 04:53:45 twilightnode nnrpd[1032659]: azpdws6b5q5j.stretchoid.com res <anonymous>
Aug 10 04:53:45 twilightnode nnrpd[1032659]: azpdws6b5q5j.stretchoid.com no_match_user <anonymous> <localhost>
Aug 10 04:53:45 twilightnode nnrpd[1032659]: azpdws6b5q5j.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 04:53:45 twilightnode nnrpd[1032659]: azpdws6b5q5j.stretchoid.com match_user <anonymous> <anonymous>
Aug 10 04:53:45 twilightnode nnrpd[1032659]: azpdws6b5q5j.stretchoid.com no_post anonymous-read
Aug 10 07:55:35 twilightnode nnrpd[1040917]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 07:55:35 twilightnode nnrpd[1040917]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 07:55:35 twilightnode nnrpd[1040917]: 193.124.20.246 res <anonymous>
Aug 10 07:55:35 twilightnode nnrpd[1040917]: 193.124.20.246 no_match_user <anonymous> <localhost>
Aug 10 07:55:35 twilightnode nnrpd[1040917]: 193.124.20.246 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 07:55:35 twilightnode nnrpd[1040917]: 193.124.20.246 match_user <anonymous> <anonymous>
Aug 10 07:55:35 twilightnode nnrpd[1040917]: 193.124.20.246 no_post anonymous-read
Aug 10 07:56:02 twilightnode nnrpd[1040933]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 07:56:02 twilightnode nnrpd[1040933]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 07:56:02 twilightnode nnrpd[1040933]: 194.88.98.93 res <anonymous>
Aug 10 07:56:02 twilightnode nnrpd[1040933]: 194.88.98.93 no_match_user <anonymous> <localhost>
Aug 10 07:56:02 twilightnode nnrpd[1040933]: 194.88.98.93 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 07:56:02 twilightnode nnrpd[1040933]: 194.88.98.93 match_user <anonymous> <anonymous>
Aug 10 07:56:02 twilightnode nnrpd[1040933]: 194.88.98.93 no_post anonymous-read
Aug 10 17:32:35 twilightnode nnrpd[1068212]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 17:32:35 twilightnode nnrpd[1068212]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 17:32:35 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com res <anonymous>
Aug 10 17:32:35 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 10 17:32:35 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 17:32:35 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 10 17:32:35 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com no_post anonymous-read
Aug 10 17:32:36 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com res <anonymous>
Aug 10 17:32:36 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 10 17:32:36 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 17:32:36 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 10 17:32:36 twilightnode nnrpd[1068212]: 107.195.132.66.censys-scanner.com no_post anonymous-read
Aug 10 20:57:27 twilightnode nnrpd[1077236]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 20:57:27 twilightnode nnrpd[1077236]: Auth strategy 'localhost' does not match client. Removing.
Aug 10 20:57:27 twilightnode nnrpd[1077236]: vmd129036.contaboserver.net res <anonymous>
Aug 10 20:57:27 twilightnode nnrpd[1077236]: vmd129036.contaboserver.net no_match_user <anonymous> <localhost>
Aug 10 20:57:27 twilightnode nnrpd[1077236]: vmd129036.contaboserver.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 10 20:57:27 twilightnode nnrpd[1077236]: vmd129036.contaboserver.net match_user <anonymous> <anonymous>
Aug 10 20:57:27 twilightnode nnrpd[1077236]: vmd129036.contaboserver.net no_post anonymous-read
Aug 10 21:29:44 twilightnode nnrpd[1078823]: Reading access from /usr/local/news/etc/readers.conf
Aug 10 21:29:44 twilightnode nnrpd[1078823]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 6851 67.4%1.0 MB 69.5%
innd 1846 18.2%242.3 KB 16.2%
innfeed 1319 13.0%197.2 KB 13.2%
nnrpd 145 1.4%16.0 KB 1.1%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 5 10162 100.0%1.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14960 49.1%
Negative hits 8645 28.4%
Do not exist 6821 22.4%
Cache misses 19 0.1%
TOTAL: 4 30445 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.009 0.0%6 1.000 1.500 2.000
article cleanup00:00:00.041 0.0%6833 0.000 0.006 0.111
article logging00:00:00.212 0.0%6849 0.000 0.031 0.136
article parse00:00:00.095 0.0%9334 0.000 0.010 0.083
article write00:00:04.123 0.0%5078 0.382 0.812 1.842
data move00:00:00.093 0.0%33019 0.000 0.003 0.024
hisgrep/artcncl00:00:00.002 0.0%6 0.000 0.333 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.884 0.0%30503 0.055 0.127 0.252
history sync00:00:00.040 0.0%311 0.000 0.129 1.500
history write00:00:03.140 0.0%6833 0.136 0.460 0.941
idle24:00:01.233100.0%32541 1216.671 2655.150 12003.740
nntp read00:00:00.525 0.0%32276 0.000 0.016 0.056
overview write00:00:02.091 0.0%5078 0.110 0.412 1.417
perl filter00:00:09.205 0.0%6833 0.588 1.347 6.349
python filter00:00:00.619 0.0%6833 0.000 0.091 0.535
site send00:00:00.076 0.0%5043 0.000 0.015 0.188
TOTAL: 1724:00:25.388100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.016 0.0%5221 0.000 0.003 0.038
article prepare00:00:00.004 0.0%18832 0.000 0.000 0.011
article read00:00:00.502 0.0%3409 0.000 0.147 1.222
backlog stats00:00:00.036 0.0%57193 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.365 0.0%30905 0.000 0.012 0.042
data write00:00:00.708 0.0%25755 0.000 0.027 0.109
idle23:59:07.968100.0%57193 485.800 1509.765 5277.553
status file00:00:00.162 0.0%269 0.000 0.602 11.000
TOTAL: 923:59:09.761100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:17.229100.0%17 83.000 1013.471 10484.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%93 0.000 0.000 0.000
TOTAL: 300:00:17.229100.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 11324 4463 5711 1150 39%45:43:16
2newsfeed.endofthelinebbs.com 4 5997 335 5033 629 5%30:24:13
3news.tebibyte.org 1 4726 105 4551 70 2%23:51:51
4news.sklaffkom.se 43 77 36 17 24 46%09:52:45
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 77 22124 4939 15312 1873 22%109:52:05
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net11.9 MB0.0 KB3.6 MB15.5 MB 76%2.8 KB
2newsfeed.endofthelinebbs.com3.3 MB0.0 KB2.5 MB5.8 MB 56%6.2 KB
3news.tebibyte.org348.5 KB0.0 KB216.1 KB564.6 KB 61%3.2 KB
4news.sklaffkom.se84.8 KB0.0 KB54.5 KB139.3 KB 60%2.3 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 515.6 MB0.0 KB6.4 MB22.0 MB 70%3.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 10 03:00:03 - 03:59:59 81 1.6% 0.02221.9 KB 1.3% 0.06
Aug 10 04:00:00 - 04:59:59 89 1.8% 0.02360.0 KB 2.1% 0.10
Aug 10 05:00:00 - 05:59:59 103 2.0% 0.03271.4 KB 1.6% 0.08
Aug 10 06:00:00 - 06:59:59 116 2.3% 0.03295.3 KB 1.7% 0.08
Aug 10 07:00:00 - 07:59:59 126 2.5% 0.04257.5 KB 1.5% 0.07
Aug 10 08:00:00 - 08:59:59 172 3.4% 0.05408.5 KB 2.4% 0.11
Aug 10 09:00:00 - 09:59:59 250 4.9% 0.07751.1 KB 4.4% 0.21
Aug 10 10:00:00 - 10:59:59 241 4.7% 0.07560.4 KB 3.3% 0.16
Aug 10 11:00:00 - 11:59:59 250 4.9% 0.07579.3 KB 3.4% 0.16
Aug 10 12:00:00 - 12:59:59 228 4.5% 0.06587.7 KB 3.4% 0.16
Aug 10 13:00:00 - 13:59:59 233 4.6% 0.06607.2 KB 3.6% 0.17
Aug 10 14:00:00 - 14:59:59 253 5.0% 0.07672.2 KB 3.9% 0.19
Aug 10 15:00:00 - 15:59:59 339 6.7% 0.091.1 MB 6.6% 0.32
Aug 10 16:00:00 - 16:59:59 303 6.0% 0.08866.1 KB 5.1% 0.24
Aug 10 17:00:00 - 17:59:59 269 5.3% 0.07819.6 KB 4.8% 0.23
Aug 10 18:00:00 - 18:59:59 283 5.6% 0.08952.5 KB 5.6% 0.26
Aug 10 19:00:00 - 19:59:59 256 5.0% 0.07616.0 KB 3.6% 0.17
Aug 10 20:00:00 - 20:59:59 241 4.7% 0.07668.0 KB 3.9% 0.19
Aug 10 21:00:00 - 21:59:59 215 4.2% 0.06557.5 KB 3.3% 0.15
Aug 10 22:00:00 - 22:59:59 211 4.2% 0.06658.9 KB 3.9% 0.18
Aug 10 23:00:00 - 23:59:59 196 3.9% 0.051021.0 KB 6.0% 0.28
Aug 11 00:00:00 - 00:59:59 144 2.8% 0.04431.2 KB 2.5% 0.12
Aug 11 01:00:00 - 01:59:59 110 2.2% 0.03427.7 KB 2.5% 0.12
Aug 11 02:00:00 - 02:59:59 362 7.1% 0.103.3 MB 19.7% 0.94
Aug 11 03:00:00 - 03:00:03 9 0.2% 3.007.4 KB 0.0% 2.48
TOTAL: 24:00:00 5080 100.0% 0.0616.7 MB 100.0% 0.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1211 1056 0 0 0 0 0 0 155
2newsfeed.endofthelinebbs.com 524 62 0 0 0 0 0 0 462
3news.sklaffkom.se 24 24 0 0 0 0 0 0 0
4news.tebibyte.org 12 12 0 0 0 0 0 0 0
TOTAL: 4 1771 1154 0 0 0 0 0 0 617

Unwanted newsgroups [Top 20]:

NewsgroupCount
hfx.general 52
de.alt.talk.unmut 51
alt.usage.english 49
pl.soc.polityka 39
pl.sci.fizyka 37
fr.sci.physique 30
de.alt.folklore.computer 29
de.test 24
alt.slack 22
pl.misc.elektronika 21
uk.legal.moderated 21
alt.bestjobsusa.computer.jobs 20
muc.lists.freebsd.bugs 19
pl.soc.prawo 19
aus.politics 18
de.comm.software.mozilla.browser 18
alt.privacy.anon-server 16
free.it.ai.general 16
it-alt.sport.calcio.juventus 15
linux.debian.user 15
TOTAL: 215 1154

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 462
EMP (phn path) 71
User-issued cancel 57
HTML Multipart 11
TOTAL: 4 601

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 7119 1360 3686 2 0 13 19%23:59:57
2sklaffkom.se 5222 1081 1733 2229 0 2 20%23:59:58
3newsfeed.endofthelinebbs.com 9134 34 4625 8 0 169 0%23:59:57
4weretis.net 525 0 514 0 0 0 0%17:47:26
TOTAL: 4 22000 2475 10558 2239 0 184 11%89:47:18
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.9 MB2.7 KB3.9 MB0.0 KB/s2.9 KB23:59:57
2sklaffkom.se1.7 MB8.8 MB10.6 MB0.1 KB/s3.3 KB23:59:58
3newsfeed.endofthelinebbs.com83.3 KB20.7 KB104.0 KB0.0 KB/s2.5 KB23:59:57
4weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s017:47:26
TOTAL: 45.7 MB8.9 MB14.5 MB0.0 KB/s3.2 KB89:47:18
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1196.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:02
2azpdws6b5q5j.stretchoid.com 2 00.0 KB 0 0 000:00:10
3vmd129036.contaboserver.net 2 00.0 KB 0 0 000:00:01
4107.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
5193.124.20.246 1 00.0 KB 0 0 000:00:00
6194.88.98.93 1 00.0 KB 0 0 000:00:00
794.154.43.21 1 00.0 KB 0 0 000:00:00
8census9.shodan.io 1 00.0 KB 0 0 000:00:01
TOTAL: 8 12 00.0 KB 0 0 000:00:17

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:03
2unresolved 3 00.0 KB 0 0 000:00:00
3*.contaboserver.net 2 00.0 KB 0 0 000:00:01
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
5*.shodan.io 1 00.0 KB 0 0 000:00:01
TOTAL: 5 12 00.0 KB 0 0 000:00:17

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdws6b5q5j.stretchoid.com 0.006 0.021 0.00000:00:10
196.172.132.66.censys-scanner.com 0.032 0.026 0.00000:00:02
107.195.132.66.censys-scanner.com 0.031 0.006 0.00000:00:01
census9.shodan.io 0.003 0.008 0.00000:00:01
vmd129036.contaboserver.net 0.011 0.018 0.00000:00:01
194.88.98.93 0.006 0.003 0.00000:00:00
94.154.43.21 0.005 0.007 0.00000:00:00
193.124.20.246 0.000 0.015 0.00000:00:00
TOTAL: 8 0.094 0.104 0.00000:00:17

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

SystemConn
107.195.132.66.censys-scanner.com 1
196.172.132.66.censys-scanner.com 1
azpdws6b5q5j.stretchoid.com 1
TOTAL: 3 3

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

CommandCount
EHLO www.censys.io 2
MGLNDD_172.234.115.63_119 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
194.88.98.93 1 1
196.172.132.66.censys-scanner.com 1 2
TOTAL: 2 2 4