Daily Usenet report for news.niel.me

Sep 29 04:15:02 -- Sep 30 04:15:01

Log entries by program:

Program nameLines%LinesSize%Size
inn 2186 27.4%407.9 KB 31.8%
innd 2118 26.5%382.3 KB 29.8%
nnrpd 1908 23.9%224.9 KB 17.5%
innfeed 1777 22.2%267.8 KB 20.9%
controlchan 1 0.0%0.2 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 6 7991 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 11914 61.7%
Negative hits 5275 27.3%
Do not exist 2128 11.0%
Cache misses 3 0.0%
TOTAL: 4 19320 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.035 0.0%24 0.000 1.458 5.000
article cancel00:00:00.004 0.0%24 0.000 0.167 1.000
article cleanup00:00:00.062 0.0%2140 0.000 0.029 0.400
article logging00:00:00.182 0.0%2140 0.000 0.085 0.385
article parse00:00:00.278 0.0%4509 0.000 0.062 0.500
article write00:00:01.070 0.0%1933 0.000 0.554 1.500
artlog/artcncl00:00:00.002 0.0%24 0.000 0.083 1.000
artlog/artcncl/perl00:00:00.003 0.0%24 0.000 0.125 1.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.118 0.0%20360 0.000 0.006 0.025
hisgrep/artcncl00:00:00.001 0.0%24 0.000 0.042 1.000
hisgrep/artcncl/perl00:00:00.001 0.0%24 0.000 0.042 1.000
hisgrep/perl00:00:00.002 0.0%34 0.000 0.059 0.500
hishave/artcncl00:00:00.000 0.0%24 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.001 0.0%24 0.000 0.042 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.321 0.0%19345 0.000 0.017 0.048
history sync00:00:00.067 0.0%392 0.000 0.171 1.000
history write00:00:00.380 0.0%2137 0.000 0.178 0.810
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:48.721100.0%22248 1324.139 3885.685 17670.971
nntp read00:00:00.561 0.0%21436 0.000 0.026 0.075
overview write00:00:00.742 0.0%1933 0.000 0.384 2.444
perl filter00:00:08.671 0.0%2137 2.029 4.058 7.750
python filter00:00:00.010 0.0%2137 0.000 0.005 0.250
site send00:00:00.811 0.0%3890 0.000 0.208 0.452
TOTAL: 24:01:09.39824:01:02.043100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%1960 0.000 0.009 0.125
article prepare00:00:00.002 0.0%696 0.000 0.003 0.125
article read00:00:00.036 0.0%172 0.000 0.209 1.000
backlog stats00:00:00.177 0.0%30375 0.000 0.006 0.040
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.432 0.0%15727 0.000 0.027 0.222
data write00:00:00.829 0.0%13933 0.010 0.059 0.250
idle23:49:53.720100.0%30375 1172.004 2824.485 9689.032
status file00:00:00.555 0.0%230 0.000 2.413 7.000
TOTAL: 23:50:00.85623:49:55.769100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:26.539 35.6%1491 0.000 58.041 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.139 0.1%3374 0.000 0.041 0.500
readart00:00:00.008 0.0%5 1.600 1.600 1.600
TOTAL: 00:04:03.00300:01:26.686 35.7%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 171

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feed-out.gegeweb.org 2 1838 1030 808 0 56%23:59:32
2i2pn.org 1 3789 394 3394 1 10%43:45:51
3news.samoylyk.net 3 3263 295 2968 0 9%37:13:09
4news.nntp4.net 1 1869 124 1666 79 6%22:44:39
5nntp.terraraq.uk 2 3191 59 3131 1 1%39:59:05
6gegeweb-out.news.tnetconsulting.net 1 382 55 326 1 14%08:40:51
7news.glou.org 2 1521 53 1428 40 3%21:59:51
8usenet.pasdenom.info 2 79 0 79 0 0%02:40:27
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 39 15932 2010 13800 122 12%201:03:25
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org3.2 MB0.0 KB0.0 KB3.2 MB100%3.2 KB
2news.samoylyk.net1.9 MB0.0 KB0.0 KB1.9 MB100%6.5 KB
3i2pn.org989.0 KB0.0 KB7.6 KB996.6 KB 99%2.5 KB
4news.nntp4.net559.4 KB0.0 KB207.5 KB766.9 KB 72%3.8 KB
5news.glou.org252.4 KB0.0 KB135.6 KB388.0 KB 65%4.2 KB
6gegeweb-out.news.tnetconsulting.net181.6 KB0.0 KB6.6 KB188.2 KB 96%3.4 KB
7nntp.terraraq.uk167.6 KB0.0 KB44.5 KB212.2 KB 79%3.5 KB
8usenet.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 97.2 MB0.0 KB401.9 KB7.6 MB 94%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 29 04:15:02 - 04:59:59 48 2.5% 0.02178.4 KB 2.6% 0.07
Sep 29 05:00:00 - 05:59:59 51 2.6% 0.01130.9 KB 1.9% 0.04
Sep 29 06:00:00 - 06:59:59 39 2.0% 0.0188.1 KB 1.3% 0.02
Sep 29 07:00:00 - 07:59:59 40 2.1% 0.01131.2 KB 1.9% 0.04
Sep 29 08:00:00 - 08:59:59 50 2.6% 0.01137.2 KB 2.0% 0.04
Sep 29 09:00:00 - 09:59:59 66 3.4% 0.02127.0 KB 1.9% 0.04
Sep 29 10:00:00 - 10:59:59 66 3.4% 0.02220.8 KB 3.2% 0.06
Sep 29 11:00:00 - 11:59:59 72 3.7% 0.02208.1 KB 3.1% 0.06
Sep 29 12:00:00 - 12:59:59 76 3.9% 0.02301.5 KB 4.4% 0.08
Sep 29 13:00:00 - 13:59:59 100 5.2% 0.03612.5 KB 9.0% 0.17
Sep 29 14:00:00 - 14:59:59 106 5.5% 0.03464.2 KB 6.8% 0.13
Sep 29 15:00:00 - 15:59:59 74 3.8% 0.02233.2 KB 3.4% 0.06
Sep 29 16:00:00 - 16:59:59 96 5.0% 0.03412.8 KB 6.1% 0.11
Sep 29 17:00:00 - 17:59:59 105 5.4% 0.03375.8 KB 5.5% 0.10
Sep 29 18:00:00 - 18:59:59 65 3.4% 0.02191.8 KB 2.8% 0.05
Sep 29 19:00:00 - 19:59:59 105 5.4% 0.03353.3 KB 5.2% 0.10
Sep 29 20:00:00 - 20:59:59 86 4.5% 0.02405.1 KB 5.9% 0.11
Sep 29 21:00:00 - 21:59:59 130 6.7% 0.04392.4 KB 5.8% 0.11
Sep 29 22:00:00 - 22:59:59 112 5.8% 0.03391.3 KB 5.7% 0.11
Sep 29 23:00:00 - 23:59:59 123 6.4% 0.03341.4 KB 5.0% 0.09
Sep 30 00:00:00 - 00:59:59 90 4.7% 0.03295.6 KB 4.3% 0.08
Sep 30 01:00:00 - 01:59:59 94 4.9% 0.03286.3 KB 4.2% 0.08
Sep 30 02:00:00 - 02:59:59 73 3.8% 0.02303.1 KB 4.4% 0.08
Sep 30 03:00:00 - 03:59:59 49 2.5% 0.01196.7 KB 2.9% 0.05
Sep 30 04:00:00 - 04:15:01 14 0.7% 0.0237.4 KB 0.5% 0.04
TOTAL: 23:59:59 1930 100.0% 0.026.7 MB 100.0% 0.08
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 96 0 0 0 0 0 0 0 96
2news.glou.org 49 0 0 0 0 0 0 0 49
3gegeweb-out.news.tnetconsulting.net 39 0 0 3 0 0 0 0 36
4usenet.pasdenom.info 23 0 0 0 0 0 0 0 23
5nntp.terraraq.uk 1 0 0 0 0 0 0 0 1
TOTAL: 5 208 0 0 3 0 0 0 0 205

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 91
EMP (phn path) 59
Bad path (news.giganews.com.POSTED) 41
Cancels abusifs 2
HTML Multipart 1
Cancel of <90db8844a5a6df0ffe1a20def21faabb0d00511f@i2pn2.org> without Cancel-Key 1
Cancel of <ae3f1e4dd1de859ae58672559aa6ea9cd1694ce6@i2pn2.org> without Cancel-Key 1
Cancel of <1ea1df0dd6d6975286db1e544e8e36f87436f0e4@i2pn2.org> without Cancel-Key 1
Cancel of <d831fbfefdb9d43df3bef1b30249a817c9862525@i2pn2.org> without Cancel-Key 1
Cancel of <6120a173563c0898ca787541087ca7bdfb463484@i2pn2.org> without Cancel-Key 1
Cancel of <511ad7303a3cf07b32e9eaadd8056ebcfb9cf983@i2pn2.org> without Cancel-Key 1
Cancel of <d23b9751906227ad3e3f79e70acf10142429d049@i2pn2.org> without Cancel-Key 1
Cancel of <27d14e217fea048cef6f14d5b54ce191afe91894@i2pn2.org> without Cancel-Key 1
Too many newsgroups (meow) 1
Cancel of <d9f850c16a917c87dc98feb0ed59f6f562a8782c@i2pn2.org> without Cancel-Key 1
Cancel of <60735ed9678db2eec6769c3bb1e68ff070bea35b@i2pn2.org> without Cancel-Key 1
TOTAL: 16 205

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.gegeweb.org 962 77 817 0 0 0 8%23:43:29
2i2pn.org 2838 76 1633 1 0 25 2%23:59:15
3news.tnetconsulting.net 3215 6 1921 2 0 13 0%23:57:32
4news.samoylyk.net 2015 4 1726 8 0 1 0%23:59:35
5news.pasdenom.info 1809 1 1598 0 0 2 0%23:55:24
6nntp.terraraq.uk 1250 0 1213 0 0 1 0%23:55:53
7news.nntp4.net 1897 0 1790 0 0 0 0%23:59:57
8news.aioe.org 0 0 0 0 0 1457 0%23:59:59
TOTAL: 8 13986 164 10698 11 0 1499 1%191:31:04
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org330.9 KB2.1 KB333.0 KB0.0 KB/s4.3 KB23:59:15
2news.gegeweb.org186.1 KB0.0 KB186.1 KB0.0 KB/s2.4 KB23:43:29
3news.samoylyk.net35.5 KB41.9 KB77.4 KB0.0 KB/s6.5 KB23:59:35
4news.tnetconsulting.net19.7 KB5.1 KB24.7 KB0.0 KB/s3.1 KB23:57:32
5news.pasdenom.info1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:55:24
6nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:53
7news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
8news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
TOTAL: 8573.9 KB49.1 KB623.0 KB0.0 KB/s3.6 KB191:31:04
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
169.10.48.174 4 55.1 KB 2 0 000:00:19
TOTAL: 1 4 55.1 KB 2 0 000:04:02

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 18 55.1 KB 2 0 000:01:17
2*.monitoring.internet-measurement.com 6 00.0 KB 0 0 000:00:13
3*.gegeweb.eu 288 00.0 KB 0 0 000:02:06
4*.stretchoid.com 3 00.0 KB 0 0 000:00:20
5*.survey.inspici.com 1 00.0 KB 0 0 000:00:03
TOTAL: 5 316 55.1 KB 2 0 000:04:02

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 5.233 4.596 1.75300:02:06
69.10.48.174 0.127 0.091 0.00000:00:19
147.185.133.44 0.034 0.009 0.00000:00:12
147.185.133.251 0.064 0.028 0.00000:00:12
198.235.24.80 0.027 0.027 0.00000:00:10
azpdss7.stretchoid.com 0.043 0.009 0.00000:00:10
198.235.24.93 0.030 0.021 0.00000:00:10
azpdcs66.stretchoid.com 0.030 0.022 0.00000:00:09
trusting.monitoring.internet-measurement.com 0.048 0.013 0.00000:00:07
199.45.154.150 0.072 0.065 0.00000:00:05
alluring.monitoring.internet-measurement.com 0.064 0.032 0.00000:00:05
server-0-3.survey.inspici.com 0.030 0.022 0.00000:00:03
206.168.34.43 0.020 0.008 0.00000:00:01
147.45.112.17 0.036 0.011 0.00000:00:01
147.45.112.151 0.024 0.024 0.00000:00:00
147.45.112.222 0.038 0.027 0.00000:00:00
203.55.131.4 0.034 0.011 0.00000:00:00
147.45.112.158 0.015 0.008 0.00000:00:00
azpdcgb11.stretchoid.com 0.011 0.011 0.00000:00:00
45.227.254.8 0.035 0.019 0.00000:00:00
TOTAL: 23 6.132 5.135 1.75300:04:02

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
199.45.154.150 3
147.45.112.151 1
azpdcs66.stretchoid.com 1
fresh.monitoring.internet-measurement.com 1
alluring.monitoring.internet-measurement.com 1
azpdcgb11.stretchoid.com 1
206.168.34.43 1
147.185.133.251 1
147.185.133.44 1
server-0-3.survey.inspici.com 1
198.235.24.93 1
147.45.112.158 1
trusting.monitoring.internet-measurement.com 1
198.235.24.80 1
147.45.112.222 1
azpdss7.stretchoid.com 1
stellar.monitoring.internet-measurement.com 1
upbeat.monitoring.internet-measurement.com 1
45.227.254.8 1
TOTAL: 23 312

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 6
TOTAL: 1 6

NNRP unrecognized commands (by host):

SystemConn
trusting.monitoring.internet-measurement.com 6
147.185.133.251 4
147.185.133.44 4
147.45.112.151 1
206.168.34.43 1
azpdcgb11.stretchoid.com 1
199.45.154.150 1
147.45.112.158 1
147.45.112.17 1
TOTAL: 9 20

NNRP unrecognized commands (by command):

CommandCount
#003 3
Accept-Encoding: gzip 3
GET / HTTP/1.1 3
Host: 45.13.105.36:563 2
User-Agent: Expanse, a Palo Alto Network... 2
EHLO www.censys.io 2
MGLNDD_45.13.105.36_119 1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d... 1
Accept: */* 1
User-Agent: Mozilla/5.0 (compatible; Int... 1
Connection: close 1
TOTAL: 11 20

NNRP client timeouts [Top 20]:

SystemConnPeer
147.45.112.158 1 1
147.45.112.17 1 1
199.45.154.150 1 2
147.45.112.151 1 1
TOTAL: 4 4 5

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 3 60.0%
2local 2 40.0%
TOTAL: 2 5100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1grisbi.test 3
2local.test 2
TOTAL: 2 5