Daily Usenet report

Jul 5 03:00:06 -- Jul 6 03:00:06

Unknown entries from news log file:

First 50 / 100 lines (50.0%)

Jul 5 03:01:24 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 5 03:46:09 twilightnode nnrpd[3254468]: Reading access from /usr/local/news/etc/readers.conf
Jul 5 03:46:09 twilightnode nnrpd[3254468]: Auth strategy 'localhost' does not match client. Removing.
Jul 5 03:46:09 twilightnode nnrpd[3254468]: 61.210.203.35.bc.googleusercontent.com res <anonymous>
Jul 5 03:46:09 twilightnode nnrpd[3254468]: 61.210.203.35.bc.googleusercontent.com no_match_user <anonymous> <localhost>
Jul 5 03:46:09 twilightnode nnrpd[3254468]: 61.210.203.35.bc.googleusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 5 03:46:09 twilightnode nnrpd[3254468]: 61.210.203.35.bc.googleusercontent.com match_user <anonymous> <anonymous>
Jul 5 03:46:09 twilightnode nnrpd[3254468]: 61.210.203.35.bc.googleusercontent.com no_post anonymous-read
Jul 5 05:34:04 twilightnode nnrpd[3258874]: Reading access from /usr/local/news/etc/readers.conf
Jul 5 05:34:04 twilightnode nnrpd[3258874]: Auth strategy 'localhost' does not match client. Removing.
Jul 5 05:34:04 twilightnode nnrpd[3258874]: 185.242.226.72 res <anonymous>
Jul 5 05:34:04 twilightnode nnrpd[3258874]: 185.242.226.72 no_match_user <anonymous> <localhost>
Jul 5 05:34:04 twilightnode nnrpd[3258874]: 185.242.226.72 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 5 05:34:04 twilightnode nnrpd[3258874]: 185.242.226.72 match_user <anonymous> <anonymous>
Jul 5 05:34:04 twilightnode nnrpd[3258874]: 185.242.226.72 no_post anonymous-read
Jul 5 06:12:00 twilightnode nnrpd[3260695]: Reading access from /usr/local/news/etc/readers.conf
Jul 5 06:12:00 twilightnode nnrpd[3260695]: Auth strategy 'localhost' does not match client. Removing.
Jul 5 06:12:00 twilightnode nnrpd[3260695]: zl-dala-us-gp1-wk124d.internet-census.org res <anonymous>
Jul 5 06:12:00 twilightnode nnrpd[3260695]: zl-dala-us-gp1-wk124d.internet-census.org no_match_user <anonymous> <localhost>
Jul 5 06:12:00 twilightnode nnrpd[3260695]: zl-dala-us-gp1-wk124d.internet-census.org no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 5 06:12:00 twilightnode nnrpd[3260695]: zl-dala-us-gp1-wk124d.internet-census.org match_user <anonymous> <anonymous>
Jul 5 06:12:00 twilightnode nnrpd[3260695]: zl-dala-us-gp1-wk124d.internet-census.org no_post anonymous-read
Jul 5 08:36:28 twilightnode nnrpd[3266433]: Reading access from /usr/local/news/etc/readers.conf
Jul 5 08:36:28 twilightnode nnrpd[3266433]: Auth strategy 'localhost' does not match client. Removing.
Jul 5 08:36:28 twilightnode nnrpd[3266433]: 66-228-62-150.ip.linodeusercontent.com res <anonymous>
Jul 5 08:36:28 twilightnode nnrpd[3266433]: 66-228-62-150.ip.linodeusercontent.com no_match_user <anonymous> <localhost>
Jul 5 08:36:28 twilightnode nnrpd[3266433]: 66-228-62-150.ip.linodeusercontent.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 5 08:36:28 twilightnode nnrpd[3266433]: 66-228-62-150.ip.linodeusercontent.com match_user <anonymous> <anonymous>
Jul 5 08:36:28 twilightnode nnrpd[3266433]: 66-228-62-150.ip.linodeusercontent.com no_post anonymous-read
Jul 5 14:16:04 twilightnode nnrpd[3280892]: Reading access from /usr/local/news/etc/readers.conf
Jul 5 14:16:04 twilightnode nnrpd[3280892]: Auth strategy 'localhost' does not match client. Removing.
Jul 5 14:16:04 twilightnode nnrpd[3280892]: 205.210.31.206 res <anonymous>
Jul 5 14:16:04 twilightnode nnrpd[3280892]: 205.210.31.206 no_match_user <anonymous> <localhost>
Jul 5 14:16:04 twilightnode nnrpd[3280892]: 205.210.31.206 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 5 14:16:04 twilightnode nnrpd[3280892]: 205.210.31.206 match_user <anonymous> <anonymous>
Jul 5 14:16:04 twilightnode nnrpd[3280892]: 205.210.31.206 no_post anonymous-read
Jul 5 18:07:25 twilightnode nnrpd[3290352]: Reading access from /usr/local/news/etc/readers.conf
Jul 5 18:07:25 twilightnode nnrpd[3290352]: Auth strategy 'localhost' does not match client. Removing.
Jul 5 18:07:25 twilightnode nnrpd[3290352]: 48.146.94.167.censys-scanner.com res <anonymous>
Jul 5 18:07:25 twilightnode nnrpd[3290352]: 48.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 5 18:07:25 twilightnode nnrpd[3290352]: 48.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 5 18:07:25 twilightnode nnrpd[3290352]: 48.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jul 5 18:07:25 twilightnode nnrpd[3290352]: 48.146.94.167.censys-scanner.com no_post anonymous-read
Jul 5 18:07:28 twilightnode nnrpd[3290353]: Reading access from /usr/local/news/etc/readers.conf
Jul 5 18:07:28 twilightnode nnrpd[3290353]: Auth strategy 'localhost' does not match client. Removing.
Jul 5 18:07:28 twilightnode nnrpd[3290353]: 48.146.94.167.censys-scanner.com res <anonymous>
Jul 5 18:07:28 twilightnode nnrpd[3290353]: 48.146.94.167.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 5 18:07:28 twilightnode nnrpd[3290353]: 48.146.94.167.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 5 18:07:28 twilightnode nnrpd[3290353]: 48.146.94.167.censys-scanner.com match_user <anonymous> <anonymous>
Jul 5 18:07:28 twilightnode nnrpd[3290353]: 48.146.94.167.censys-scanner.com no_post anonymous-read

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 41342 84.9%5.6 MB 80.3%
inn 5985 12.3%1.1 MB 16.1%
innd 1217 2.5%245.6 KB 3.4%
nnrpd 158 0.3%18.1 KB 0.3%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 48704 100.0%7.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14825 50.8%
Negative hits 8426 28.9%
Do not exist 5935 20.3%
Cache misses 7 0.0%
TOTAL: 4 29193 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.003 0.0%2 1.000 1.500 2.000
article cleanup00:00:00.059 0.0%5956 0.000 0.010 0.115
article logging00:00:00.231 0.0%5983 0.000 0.039 0.150
article parse00:00:00.102 0.0%8286 0.000 0.012 0.083
article write00:00:04.191 0.0%4294 0.500 0.976 2.333
data move00:00:00.082 0.0%31439 0.000 0.003 0.031
hisgrep/artcncl00:00:00.002 0.0%2 0.000 1.000 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.524 0.0%29294 0.087 0.154 0.422
history sync00:00:00.083 0.0%406 0.000 0.204 1.333
history write00:00:03.209 0.0%5956 0.148 0.539 1.167
idle24:00:35.410100.0%31234 462.662 2767.350 10180.610
nntp read00:00:00.735 0.0%30905 0.000 0.024 0.075
overview write00:00:02.248 0.0%4294 0.172 0.524 2.038
perl filter00:00:11.205 0.0%5956 1.028 1.881 18.077
python filter00:00:00.887 0.0%5956 0.024 0.149 1.692
site send00:00:00.106 0.0%4294 0.000 0.025 0.133
TOTAL: 1724:01:03.077100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.032 0.0%9901 0.000 0.003 0.038
article prepare00:00:00.000 0.0%7920 0.000 0.000 0.000
article read00:00:00.465 0.0%1980 0.000 0.235 1.200
backlog stats00:00:00.085 0.0%63006 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.463 0.0%28147 0.000 0.016 0.044
data write00:00:00.736 0.0%23953 0.005 0.031 0.167
idle23:58:54.261100.0%63006 1008.481 1370.255 2182.182
status file00:00:10.431 0.0%17128 0.439 0.609 0.778
TOTAL: 923:59:06.473100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:27.251100.0%21 124.500 1297.667 5250.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%172 0.000 0.006 0.021
TOTAL: 300:00:27.252100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 2 11230 4108 5976 1146 36%51:15:50
2newsfeed.endofthelinebbs.com 2 4188 223 3515 450 5%22:05:24
3news.tebibyte.org 2 6380 55 6279 46 0%23:59:35
4news.sklaffkom.se 43 135 42 41 52 31%12:50:06
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 74 21933 4428 15811 1694 20%110:10:55
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net15.2 MB0.0 KB3.3 MB18.5 MB 82%3.6 KB
2newsfeed.endofthelinebbs.com1.7 MB0.0 KB1.7 MB3.4 MB 50%5.2 KB
3news.sklaffkom.se180.9 KB0.0 KB111.4 KB292.3 KB 61%3.1 KB
4news.tebibyte.org156.8 KB0.0 KB137.2 KB294.0 KB 53%2.9 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 517.2 MB0.0 KB5.3 MB22.5 MB 76%3.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 5 03:00:06 - 03:59:59 110 2.6% 0.03582.0 KB 3.4% 0.16
Jul 5 04:00:00 - 04:59:59 100 2.3% 0.03376.4 KB 2.2% 0.10
Jul 5 05:00:00 - 05:59:59 113 2.6% 0.03266.9 KB 1.5% 0.07
Jul 5 06:00:00 - 06:59:59 135 3.1% 0.04395.8 KB 2.3% 0.11
Jul 5 07:00:00 - 07:59:59 117 2.7% 0.03306.6 KB 1.8% 0.09
Jul 5 08:00:00 - 08:59:59 146 3.4% 0.04427.5 KB 2.5% 0.12
Jul 5 09:00:00 - 09:59:59 209 4.9% 0.06545.0 KB 3.2% 0.15
Jul 5 10:00:00 - 10:59:59 223 5.2% 0.06643.1 KB 3.7% 0.18
Jul 5 11:00:00 - 11:59:59 166 3.9% 0.05403.5 KB 2.3% 0.11
Jul 5 12:00:00 - 12:59:59 215 5.0% 0.06550.0 KB 3.2% 0.15
Jul 5 13:00:00 - 13:59:59 239 5.6% 0.07810.0 KB 4.7% 0.23
Jul 5 14:00:00 - 14:59:59 174 4.1% 0.05620.5 KB 3.6% 0.17
Jul 5 15:00:00 - 15:59:59 216 5.0% 0.06693.8 KB 4.0% 0.19
Jul 5 16:00:00 - 16:59:59 240 5.6% 0.07725.3 KB 4.2% 0.20
Jul 5 17:00:00 - 17:59:59 214 5.0% 0.065.2 MB 30.7% 1.47
Jul 5 18:00:00 - 18:59:59 205 4.8% 0.06504.1 KB 2.9% 0.14
Jul 5 19:00:00 - 19:59:59 216 5.0% 0.06576.1 KB 3.3% 0.16
Jul 5 20:00:00 - 20:59:59 250 5.8% 0.07629.4 KB 3.6% 0.17
Jul 5 21:00:00 - 21:59:59 223 5.2% 0.06749.6 KB 4.3% 0.21
Jul 5 22:00:00 - 22:59:59 212 4.9% 0.06616.5 KB 3.6% 0.17
Jul 5 23:00:00 - 23:59:59 204 4.7% 0.06513.9 KB 3.0% 0.14
Jul 6 00:00:00 - 00:59:59 136 3.2% 0.04393.8 KB 2.3% 0.11
Jul 6 01:00:00 - 01:59:59 106 2.5% 0.03291.3 KB 1.7% 0.08
Jul 6 02:00:00 - 02:59:59 112 2.6% 0.03279.9 KB 1.6% 0.08
Jul 6 03:00:00 - 03:00:06 15 0.3% 2.5078.1 KB 0.5% 13.02
TOTAL: 24:00:00 4296 100.0% 0.0516.9 MB 100.0% 0.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1140 1039 0 0 0 0 0 0 101
2newsfeed.endofthelinebbs.com 466 99 0 0 0 0 0 0 367
3news.sklaffkom.se 52 52 0 0 0 0 0 0 0
4news.tebibyte.org 31 31 0 0 0 0 0 0 0
TOTAL: 4 1689 1221 0 0 0 0 0 0 468

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.usage.english 53
alt.checkmate 36
pl.soc.prawo 35
eternal-september.talk 34
fido7.ukrnews 29
de.soc.recht.misc 24
pl.soc.polityka 24
fr.sci.maths 23
pl.pregierz 23
hfx.general 19
it.discussioni.auto 19
it.sport.formula1 19
linux.debian.user 19
uk.telecom 19
alt.atheism 17
alt.food.fast-food 16
de.alt.fan.aldi 16
cn.comp 15
de.alt.comm.android 15
de.soc.recht.arbeit+soziales 15
TOTAL: 367 1221

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 367
User-issued cancel 51
EMP (phn path) 13
HTML Multipart 4
Too many newsgroups 3
Too many newsgroups (meow) 3
TOTAL: 6 441

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1sklaffkom.se 4551 831 2305 1152 0 3 18%23:59:55
2news.tebibyte.org 2839 0 0 0 0 7129 0%23:59:54
3newsfeed.endofthelinebbs.com 2784 0 0 0 0 6835 0%23:59:54
4weretis.net 355 0 335 0 0 0 0%19:04:29
5chimia.se 0 0 0 0 0 4296 0%23:59:55
6sklaff.dev 0 0 0 0 0 4290 0%23:59:12
7thebunker.nu 0 0 0 0 0 4296 0%23:59:55
8usenet.blueworldhosting.com 0 0 0 0 0 3883 0%23:59:55
TOTAL: 8 10529 831 2640 1152 0 30732 7%187:03:09
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1sklaffkom.se1.3 MB4.2 MB5.5 MB0.1 KB/s2.8 KB23:59:55
2chimia.se0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:12
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s019:04:29
TOTAL: 81.3 MB4.2 MB5.5 MB0.0 KB/s2.8 KB187:03:09
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1138.172.132.66.censys-scanner.com 3 00.0 KB 0 0 000:00:04
248.146.94.167.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3180.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
4185.242.226.72 1 00.0 KB 0 0 000:00:00
5205.210.31.206 1 00.0 KB 0 0 000:00:00
661.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
766-228-62-150.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:03
8zl-dala-us-gp1-wk124d.internet-census.org 1 00.0 KB 0 0 000:00:10
TOTAL: 8 12 00.0 KB 0 0 000:00:27

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.66.censys-scanner.com 4 00.0 KB 0 0 000:00:08
2*.167.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3unresolved 2 00.0 KB 0 0 000:00:00
4*.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
5*.internet-census.org 1 00.0 KB 0 0 000:00:10
6*.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:03
TOTAL: 6 12 00.0 KB 0 0 000:00:27

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
zl-dala-us-gp1-wk124d.internet-census.org 0.001 0.017 0.00000:00:10
138.172.132.66.censys-scanner.com 0.037 0.025 0.00000:00:04
48.146.94.167.censys-scanner.com 0.039 0.035 0.00000:00:04
180.186.132.66.censys-scanner.com 0.025 0.021 0.00000:00:03
66-228-62-150.ip.linodeusercontent.com 0.004 0.017 0.00000:00:03
185.242.226.72 0.005 0.014 0.00000:00:00
61.210.203.35.bc.googleusercontent.com 0.005 0.015 0.00000:00:00
205.210.31.206 0.002 0.021 0.00000:00:00
TOTAL: 8 0.118 0.165 0.00000:00:27

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

SystemConn
61.210.203.35.bc.googleusercontent.com 4
185.242.226.72 2
138.172.132.66.censys-scanner.com 1
180.186.132.66.censys-scanner.com 1
48.146.94.167.censys-scanner.com 1
TOTAL: 5 9

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

CommandCount
EHLO www.censys.io 3
#001 1
#026#003#001 1
Accept-Encoding: gzip 1
GET / HTTP/1.1 1
Host: 172.234.115.63:119 1
User-Agent: Hello from Palo Alto Network... 1
TOTAL: 7 9

NNRP client timeouts [Top 20]:

SystemConnPeer
138.172.132.66.censys-scanner.com 1 2
48.146.94.167.censys-scanner.com 1 2
TOTAL: 2 2 6
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