Daily Usenet report

Aug 4 03:00:05 -- Aug 5 03:00:05

Unknown entries from news log file:

First 50 / 100 lines (50.0%)

Aug 4 03:01:25 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Aug 4 03:09:19 twilightnode nnrpd[607608]: Reading access from /usr/local/news/etc/readers.conf
Aug 4 03:09:19 twilightnode nnrpd[607608]: Auth strategy 'localhost' does not match client. Removing.
Aug 4 03:09:19 twilightnode nnrpd[607608]: 109.155.45.199.censys-scanner.com res <anonymous>
Aug 4 03:09:19 twilightnode nnrpd[607608]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 4 03:09:19 twilightnode nnrpd[607608]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 4 03:09:19 twilightnode nnrpd[607608]: 109.155.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Aug 4 03:09:19 twilightnode nnrpd[607608]: 109.155.45.199.censys-scanner.com no_post anonymous-read
Aug 4 03:09:24 twilightnode nnrpd[607609]: Reading access from /usr/local/news/etc/readers.conf
Aug 4 03:09:24 twilightnode nnrpd[607609]: Auth strategy 'localhost' does not match client. Removing.
Aug 4 03:09:24 twilightnode nnrpd[607609]: 109.155.45.199.censys-scanner.com res <anonymous>
Aug 4 03:09:24 twilightnode nnrpd[607609]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 4 03:09:24 twilightnode nnrpd[607609]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 4 03:09:24 twilightnode nnrpd[607609]: 109.155.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Aug 4 03:09:24 twilightnode nnrpd[607609]: 109.155.45.199.censys-scanner.com no_post anonymous-read
Aug 4 03:09:32 twilightnode nnrpd[607610]: Reading access from /usr/local/news/etc/readers.conf
Aug 4 03:09:32 twilightnode nnrpd[607610]: Auth strategy 'localhost' does not match client. Removing.
Aug 4 03:09:32 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com res <anonymous>
Aug 4 03:09:32 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 4 03:09:32 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 4 03:09:32 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Aug 4 03:09:32 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com no_post anonymous-read
Aug 4 03:09:33 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com res <anonymous>
Aug 4 03:09:33 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 4 03:09:33 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 4 03:09:33 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com match_user <anonymous> <anonymous>
Aug 4 03:09:33 twilightnode nnrpd[607610]: 109.155.45.199.censys-scanner.com no_post anonymous-read
Aug 4 03:30:43 twilightnode nnrpd[608563]: Reading access from /usr/local/news/etc/readers.conf
Aug 4 03:30:43 twilightnode nnrpd[608563]: Auth strategy 'localhost' does not match client. Removing.
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com res <anonymous>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com no_post anonymous-read
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com res <anonymous>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Aug 4 03:30:43 twilightnode nnrpd[608563]: 210.172.132.66.censys-scanner.com no_post anonymous-read
Aug 4 05:10:18 twilightnode nnrpd[613088]: Reading access from /usr/local/news/etc/readers.conf
Aug 4 05:10:18 twilightnode nnrpd[613088]: Auth strategy 'localhost' does not match client. Removing.
Aug 4 05:10:18 twilightnode nnrpd[613088]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net res <anonymous>
Aug 4 05:10:18 twilightnode nnrpd[613088]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net no_match_user <anonymous> <localhost>
Aug 4 05:10:18 twilightnode nnrpd[613088]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Aug 4 05:10:18 twilightnode nnrpd[613088]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net match_user <anonymous> <anonymous>
Aug 4 05:10:18 twilightnode nnrpd[613088]: 172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net no_post anonymous-read
Aug 4 06:02:42 twilightnode nnrpd[615336]: Reading access from /usr/local/news/etc/readers.conf
Aug 4 06:02:42 twilightnode nnrpd[615336]: Auth strategy 'localhost' does not match client. Removing.
Aug 4 06:02:42 twilightnode nnrpd[615336]: 73.195.132.66.censys-scanner.com res <anonymous>
Aug 4 06:02:42 twilightnode nnrpd[615336]: 73.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>

Log entries by program:

Program nameLines%LinesSize%Size
inn 7370 73.6%1.1 MB 74.2%
innd 1299 13.0%190.1 KB 12.6%
innfeed 1197 11.9%183.1 KB 12.1%
nnrpd 151 1.5%16.6 KB 1.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 10018 100.0%1.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14194 45.5%
Negative hits 9699 31.1%
Do not exist 7275 23.3%
Cache misses 5 0.0%
TOTAL: 4 31173 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.007 0.0%3 2.000 2.333 3.000
article cleanup00:00:00.048 0.0%7298 0.000 0.007 0.158
article logging00:00:00.238 0.0%7375 0.000 0.032 0.167
article parse00:00:00.201 0.0%19457 0.000 0.010 0.074
article write00:00:04.601 0.0%5054 0.375 0.910 3.214
data move00:00:00.128 0.0%44776 0.000 0.003 0.047
hisgrep/artcncl00:00:00.006 0.0%3 1.000 2.000 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.579 0.0%31289 0.083 0.146 0.493
history sync00:00:00.035 0.0%326 0.000 0.107 1.000
history write00:00:03.486 0.0%7298 0.182 0.478 0.918
idle24:00:00.962100.0%42628 137.410 2026.859 13648.773
nntp read00:00:00.833 0.0%42361 0.000 0.020 0.092
overview write00:00:02.242 0.0%5054 0.147 0.444 2.200
perl filter00:00:08.798 0.0%7298 0.548 1.206 13.368
python filter00:00:00.566 0.0%7298 0.000 0.078 0.842
site send00:00:00.097 0.0%5032 0.000 0.019 0.234
TOTAL: 1724:00:26.827100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.014 0.0%5186 0.000 0.003 0.120
article prepare00:00:00.000 0.0%19492 0.000 0.000 0.000
article read00:00:00.609 0.0%3490 0.000 0.174 0.727
backlog stats00:00:00.036 0.0%54981 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.397 0.0%30161 0.000 0.013 0.045
data write00:00:00.621 0.0%25016 0.000 0.025 0.120
idle23:56:52.961100.0%54981 313.459 1568.050 5408.523
status file00:00:00.127 0.0%209 0.000 0.608 3.000
TOTAL: 923:56:54.765100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:20.160100.0%18 75.500 1120.000 10462.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%127 0.000 0.008 0.200
TOTAL: 300:00:20.161100.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 5 11774 4455 6023 1296 37%49:12:26
2newsfeed.endofthelinebbs.com 3 4846 598 3354 894 12%20:22:46
3news.sklaffkom.se 34 81 51 6 24 62%08:59:04
4news.tebibyte.org 1 1911 26 1868 17 1%11:50:20
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 68 18612 5130 11251 2231 27%90:24:36
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.endofthelinebbs.com13.7 MB0.0 KB43.0 MB56.7 MB 24%38.9 KB
2twilightnode-out.news.weretis.net13.1 MB0.0 KB3.7 MB16.7 MB 77%3.0 KB
3news.tebibyte.org189.9 KB0.0 KB92.7 KB282.6 KB 67%6.6 KB
4news.sklaffkom.se153.8 KB0.0 KB55.9 KB209.7 KB 73%2.8 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 527.1 MB0.0 KB46.8 MB73.9 MB 36%10.3 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 4 03:00:05 - 03:59:59 105 2.1% 0.03614.5 KB 3.2% 0.17
Aug 4 04:00:00 - 04:59:59 110 2.2% 0.03675.0 KB 3.5% 0.19
Aug 4 05:00:00 - 05:59:59 164 3.2% 0.051.1 MB 5.8% 0.31
Aug 4 06:00:00 - 06:59:59 129 2.6% 0.04382.4 KB 2.0% 0.11
Aug 4 07:00:00 - 07:59:59 146 2.9% 0.04754.4 KB 4.0% 0.21
Aug 4 08:00:00 - 08:59:59 191 3.8% 0.05707.5 KB 3.7% 0.20
Aug 4 09:00:00 - 09:59:59 248 4.9% 0.07636.9 KB 3.3% 0.18
Aug 4 10:00:00 - 10:59:59 214 4.2% 0.06743.7 KB 3.9% 0.21
Aug 4 11:00:00 - 11:59:59 250 4.9% 0.07935.8 KB 4.9% 0.26
Aug 4 12:00:00 - 12:59:59 236 4.7% 0.07687.8 KB 3.6% 0.19
Aug 4 13:00:00 - 13:59:59 267 5.3% 0.07756.0 KB 4.0% 0.21
Aug 4 14:00:00 - 14:59:59 295 5.8% 0.08954.1 KB 5.0% 0.27
Aug 4 15:00:00 - 15:59:59 350 6.9% 0.101.3 MB 7.2% 0.38
Aug 4 16:00:00 - 16:59:59 336 6.7% 0.091.1 MB 5.7% 0.30
Aug 4 17:00:00 - 17:59:59 291 5.8% 0.081.0 MB 5.5% 0.29
Aug 4 18:00:00 - 18:59:59 246 4.9% 0.07862.2 KB 4.5% 0.24
Aug 4 19:00:00 - 19:59:59 238 4.7% 0.07750.3 KB 3.9% 0.21
Aug 4 20:00:00 - 20:59:59 296 5.9% 0.081.2 MB 6.5% 0.34
Aug 4 21:00:00 - 21:59:59 214 4.2% 0.06682.9 KB 3.6% 0.19
Aug 4 22:00:00 - 22:59:59 210 4.2% 0.06738.7 KB 3.9% 0.21
Aug 4 23:00:00 - 23:59:59 146 2.9% 0.04688.6 KB 3.6% 0.19
Aug 5 00:00:00 - 00:59:59 174 3.4% 0.05720.3 KB 3.8% 0.20
Aug 5 01:00:00 - 01:59:59 94 1.9% 0.03559.0 KB 2.9% 0.16
Aug 5 02:00:00 - 02:59:59 93 1.8% 0.03352.7 KB 1.9% 0.10
Aug 5 03:00:00 - 03:00:05 9 0.2% 1.807.6 KB 0.0% 1.51
TOTAL: 24:00:00 5052 100.0% 0.0618.6 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1321 1047 0 0 0 0 0 0 274
2newsfeed.endofthelinebbs.com 931 596 0 0 0 0 0 0 335
3news.tebibyte.org 41 33 0 0 0 0 0 0 8
4news.sklaffkom.se 25 24 0 0 0 0 0 0 1
TOTAL: 4 2318 1700 0 0 0 0 0 0 618

Unwanted newsgroups [Top 20]:

NewsgroupCount
microsoft.public.windowsxp.general 262
microsoft.public.windowsxp.help_and_support 262
it.hobby.cucina 38
uk.legal.moderated 34
alt.prophecies.nostradamus 33
alt.checkmate 31
uk.media.radio.archers 31
fido7.ukrnews 30
it.discussioni.commercialisti 27
pl.misc.telefonia.gsm 27
pl.soc.prawo 27
de.alt.fan.pluesch 25
hfx.general 24
lada.talk 24
linux.debian.kernel 23
cn.comp 20
de.comm.software.mozilla.browser 19
it-alt.sport.calcio.juventus 19
alt.food.fast-food 18
alt.slack 18
TOTAL: 186 1700

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 339
EMP (phn path) 98
User-issued cancel 80
Too many newsgroups 13
HTML Multipart 6
Too many newsgroups (meow) 5
TOTAL: 6 541

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 76
news.sklaffkom.se 1
TOTAL: 2 77
TOTAL: 1 77

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 6655 1418 3608 2 0 2 21%23:59:55
2sklaffkom.se 5156 1136 1623 2270 0 7 22%23:59:55
3newsfeed.endofthelinebbs.com 8411 43 4408 8 0 156 0%23:59:54
4weretis.net 722 1 705 0 0 0 0%23:00:23
TOTAL: 4 20944 2598 10344 2280 0 165 12%95:00:07
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org3.8 MB263.0 KB4.1 MB0.0 KB/s2.9 KB23:59:55
2sklaffkom.se1.9 MB11.1 MB12.9 MB0.2 KB/s3.9 KB23:59:55
3newsfeed.endofthelinebbs.com124.3 KB13.8 KB138.1 KB0.0 KB/s2.7 KB23:59:54
4weretis.net1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:00:23
TOTAL: 45.8 MB11.3 MB17.1 MB0.1 KB/s3.6 KB95:00:07
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1109.155.45.199.censys-scanner.com 3 00.0 KB 0 0 000:00:04
273.195.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:01
3azpdcs8cd7a8.stretchoid.com 2 00.0 KB 0 0 000:00:10
4172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
5198.235.24.6 1 00.0 KB 0 0 000:00:01
6210.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
7o073.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 12 00.0 KB 0 0 000:00:20

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:02
2*.199.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.ip.frontiernet.net 1 00.0 KB 0 0 000:00:00
5*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
6unresolved 1 00.0 KB 0 0 000:00:01
TOTAL: 6 12 00.0 KB 0 0 000:00:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcs8cd7a8.stretchoid.com 0.007 0.014 0.00000:00:10
109.155.45.199.censys-scanner.com 0.031 0.026 0.00000:00:04
210.172.132.66.censys-scanner.com 0.027 0.010 0.00000:00:01
73.195.132.66.censys-scanner.com 0.028 0.035 0.00000:00:01
198.235.24.6 0.002 0.011 0.00000:00:01
o073.scanner.modat.io 0.000 0.015 0.00000:00:00
172-99-61-198.e55debe299579139e7cfe9be84b63964.ip.frontiernet.net 0.005 0.014 0.00000:00:00
TOTAL: 7 0.100 0.125 0.00000:00:20

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

SystemConn
109.155.45.199.censys-scanner.com 1
210.172.132.66.censys-scanner.com 1
73.195.132.66.censys-scanner.com 1
azpdcs8cd7a8.stretchoid.com 1
TOTAL: 4 4

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
109.155.45.199.censys-scanner.com 1 2
73.195.132.66.censys-scanner.com 1 2
TOTAL: 2 2 5