Daily Usenet report for news.niel.me

Mar 10 04:15:02 -- Mar 11 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Mar 10 12:03:03 rpi-studio innd: filter: No Cancel-Key[sha1:mDTZ82im/UmTYbhX0CIiKDtz1jU= sha256:zeaBqev1s4J1xmtUwzMwIg07Izm/yyQStzqh3rFAQus=#015#012#011sha1:EdJaVFi/COa6q0R+qSarqRvUKXw= sha256:aurOzPDQWUjv9ALjPhNOJGFrdexWPnyndO/9sZLAgLY=] matches Cancel-Lock[sha1:3EtnhcWbenSp0UIDkQuV7VCh9ms=] target=<usk3s6$cdu$1@rasp.pasdenom.info>
Mar 10 14:31:38 rpi-studio innd: filter: No Cancel-Key[sha1:Hhx5RmGaZdJvxN++U9RJyOVRDX0= sha256:ANYCN75R8bK0MQH91m+GBhV3qteCiEbiq+gMN7cj0xw=] matches Cancel-Lock[sha1:ztZT5K23Zh0X6/SCmr0W+50Bgs8= sha256:jv70e/4S+ik5W/iGS5q9dBU4kjKItPTI3v9r1ucjfF0=] target=<uskc9e$sd1$1@rasp.pasdenom.info>

Log entries by program:

Program nameLines%LinesSize%Size
inn 3006 33.4%546.2 KB 37.9%
innd 2380 26.5%410.9 KB 28.5%
innfeed 1812 20.1%281.4 KB 19.5%
nnrpd 1798 20.0%201.7 KB 14.0%
controlchan 1 0.0%0.2 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 6 8998 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 15429 63.9%
Negative hits 5762 23.8%
Do not exist 2969 12.3%
Cache misses 4 0.0%
TOTAL: 4 24164 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.022 0.0%16 1.000 1.375 4.000
article cancel00:00:00.002 0.0%17 0.000 0.118 0.500
article cleanup00:00:00.060 0.0%2979 0.000 0.020 0.400
article logging00:00:00.227 0.0%2980 0.000 0.076 0.286
article parse00:00:00.337 0.0%6536 0.000 0.052 0.231
article write00:00:01.251 0.0%2538 0.000 0.493 1.400
artlog/artcncl00:00:00.001 0.0%17 0.000 0.059 0.500
artlog/artcncl/perl00:00:00.002 0.0%16 0.000 0.125 1.000
data move00:00:00.142 0.0%25146 0.000 0.006 0.022
hisgrep/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
hisgrep/perl00:00:00.000 0.0%20 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.363 0.0%24223 0.000 0.015 0.044
history sync00:00:00.048 0.0%405 0.000 0.119 1.000
history write00:00:00.461 0.0%2979 0.000 0.155 0.667
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:49.081100.0%27674 1413.287 3123.838 30011.550
nntp read00:00:00.700 0.0%26921 0.000 0.026 0.084
overview write00:00:01.410 0.0%2538 0.000 0.556 6.500
perl filter00:00:11.338 0.0%2979 2.097 3.806 9.000
python filter00:00:00.010 0.0%2979 0.000 0.003 0.143
site send00:00:01.153 0.0%5256 0.000 0.219 0.676
TOTAL: 24:01:14.74724:01:06.608100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%2620 0.000 0.008 0.100
article prepare00:00:00.002 0.0%964 0.000 0.002 0.125
article read00:00:00.024 0.0%219 0.000 0.110 3.000
backlog stats00:00:00.209 0.0%40072 0.000 0.005 0.023
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.539 0.0%21598 0.000 0.025 0.074
data write00:00:00.998 0.0%18882 0.009 0.053 0.182
idle23:50:50.303100.0%40072 878.375 2142.401 8131.054
status file00:00:00.390 0.0%214 0.667 1.822 9.000
TOTAL: 23:50:55.18323:50:52.485100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:17.556 16.5%1452 10.200 12.091 1048.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.090 0.1%3199 0.000 0.028 1.000
TOTAL: 00:01:46.23000:00:17.646 16.6%----

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 2444 1277 1167 0 52%23:59:55
2news.nntp4.net 1 3194 378 2504 312 11%27:35:45
3usenet.pasdenom.info 4 2420 316 2083 21 13%36:08:48
4gegeweb-out.news.tnetconsulting.net 2 2088 270 1770 48 12%24:10:28
5i2pn.org 1 2547 238 2309 0 9%24:00:22
6nntp.terraraq.uk 4 3954 167 3781 6 4%40:33:26
7news.glou.org 2 2012 90 1827 95 4%23:59:11
8news.samoylyk.net 2 2538 37 2501 0 1%23:51:03
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 43 21197 2773 17942 482 13%224:18:58
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org4.2 MB0.0 KB0.0 KB4.2 MB100%3.4 KB
2news.nntp4.net1.6 MB0.0 KB1.1 MB2.7 MB 58%4.0 KB
3gegeweb-out.news.tnetconsulting.net868.7 KB0.0 KB286.1 KB1.1 MB 75%3.6 KB
4usenet.pasdenom.info738.5 KB0.0 KB27.2 KB765.7 KB 96%2.3 KB
5i2pn.org631.0 KB0.0 KB0.0 KB631.0 KB100%2.7 KB
6nntp.terraraq.uk478.0 KB0.0 KB52.4 KB530.5 KB 90%3.1 KB
7news.glou.org319.9 KB0.0 KB320.7 KB640.6 KB 49%3.5 KB
8news.samoylyk.net161.8 KB0.0 KB0.0 KB161.8 KB100%4.4 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 98.9 MB0.0 KB1.8 MB10.7 MB 83%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 10 04:15:02 - 04:59:59 27 1.1% 0.0162.9 KB 0.7% 0.02
Mar 10 05:00:00 - 05:59:59 58 2.3% 0.02164.1 KB 2.0% 0.05
Mar 10 06:00:00 - 06:59:59 67 2.6% 0.02199.2 KB 2.4% 0.06
Mar 10 07:00:00 - 07:59:59 65 2.6% 0.02235.5 KB 2.8% 0.07
Mar 10 08:00:00 - 08:59:59 51 2.0% 0.01125.3 KB 1.5% 0.03
Mar 10 09:00:00 - 09:59:59 59 2.3% 0.02181.8 KB 2.2% 0.05
Mar 10 10:00:00 - 10:59:59 55 2.2% 0.02206.3 KB 2.5% 0.06
Mar 10 11:00:00 - 11:59:59 84 3.3% 0.02343.4 KB 4.1% 0.10
Mar 10 12:00:00 - 12:59:59 95 3.8% 0.03286.1 KB 3.4% 0.08
Mar 10 13:00:00 - 13:59:59 100 4.0% 0.03352.0 KB 4.2% 0.10
Mar 10 14:00:00 - 14:59:59 139 5.5% 0.04449.4 KB 5.4% 0.12
Mar 10 15:00:00 - 15:59:59 110 4.3% 0.03332.9 KB 4.0% 0.09
Mar 10 16:00:00 - 16:59:59 155 6.1% 0.04476.1 KB 5.7% 0.13
Mar 10 17:00:00 - 17:59:59 140 5.5% 0.04451.2 KB 5.4% 0.13
Mar 10 18:00:00 - 18:59:59 130 5.1% 0.04425.2 KB 5.1% 0.12
Mar 10 19:00:00 - 19:59:59 175 6.9% 0.05582.9 KB 6.9% 0.16
Mar 10 20:00:00 - 20:59:59 209 8.3% 0.06710.8 KB 8.5% 0.20
Mar 10 21:00:00 - 21:59:59 162 6.4% 0.04451.0 KB 5.4% 0.13
Mar 10 22:00:00 - 22:59:59 155 6.1% 0.04537.7 KB 6.4% 0.15
Mar 10 23:00:00 - 23:59:59 156 6.2% 0.04577.1 KB 6.9% 0.16
Mar 11 00:00:00 - 00:59:59 108 4.3% 0.03411.3 KB 4.9% 0.11
Mar 11 01:00:00 - 01:59:59 90 3.6% 0.03267.7 KB 3.2% 0.07
Mar 11 02:00:00 - 02:59:59 67 2.6% 0.02222.2 KB 2.6% 0.06
Mar 11 03:00:00 - 03:59:59 64 2.5% 0.02313.0 KB 3.7% 0.09
Mar 11 04:00:00 - 04:15:01 9 0.4% 0.0128.5 KB 0.3% 0.03
TOTAL: 23:59:59 2530 100.0% 0.038.2 MB 100.0% 0.10
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 287 0 0 0 0 0 0 0 287
2news.glou.org 95 0 0 0 0 0 0 0 95
3gegeweb-out.news.tnetconsulting.net 51 0 0 0 0 0 0 0 51
4usenet.pasdenom.info 6 0 0 0 0 0 0 0 6
5nntp.terraraq.uk 4 0 0 0 0 0 0 0 4
TOTAL: 5 443 0 0 0 0 0 0 0 443

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 273
EMP (phn path) 78
Bad path (news.giganews.com.POSTED) 74
Too many newsgroups (meow) 7
Cancel for rejected article 2
Cancels abusifs 2
HTML Multipart 2
EMP (md5) 1
Cancel of non-existing ID <ur8bgm$2pbu$1@dont-email.me> 1
No Cancel-Key matches Cancel-Lock. target=<uskc9e$sd1$1@rasp.pasdenom.info> 1
Cancel of non-existing ID <usligu$38011$1@dont-email.me> 1
TOTAL: 11 442

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
nntp.terraraq.uk 1
TOTAL: 1 1
TOTAL: 1 1

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 3952 82 2213 14 0 49 2%23:59:35
2news.gegeweb.org 1318 80 1161 0 0 0 6%23:55:36
3news.samoylyk.net 3653 30 2441 0 0 40 0%23:57:19
4news.tnetconsulting.net 4205 13 2498 18 0 24 0%23:58:40
5news.pasdenom.info 2201 3 1919 0 0 1 0%23:59:56
6nntp.terraraq.uk 1554 2 1413 0 0 1 0%23:59:56
7news.aioe.org 0 0 0 0 0 2087 0%23:59:56
8news.nntp4.net 2303 0 2164 0 0 1 0%23:59:46
TOTAL: 8 19186 210 13809 32 0 2203 1%191:50:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.gegeweb.org233.7 KB0.0 KB233.7 KB0.0 KB/s2.9 KB23:55:36
2i2pn.org190.6 KB62.8 KB253.3 KB0.0 KB/s2.6 KB23:59:35
3news.samoylyk.net66.4 KB0.0 KB66.4 KB0.0 KB/s2.2 KB23:57:19
4news.tnetconsulting.net26.1 KB49.5 KB75.6 KB0.0 KB/s2.4 KB23:58:40
5nntp.terraraq.uk6.0 KB0.0 KB6.0 KB0.0 KB/s3.0 KB23:59:56
6news.pasdenom.info4.6 KB0.0 KB4.6 KB0.0 KB/s1.5 KB23:59:56
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
8news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
TOTAL: 8527.4 KB112.3 KB639.7 KB0.0 KB/s2.6 KB191:50:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
145.227.254.8 1 00.0 KB 0 0 000:00:00
2wondrous.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
3supportive.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
4194.165.16.10 1 00.0 KB 0 0 000:00:00
5fabulous.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:06
6mail.gegeweb.eu 288 00.0 KB 0 0 000:01:26
7172.104.238.162 1 00.0 KB 0 0 000:00:02
8fascinating.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
9fortunate.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
10192.241.235.28 1 00.0 KB 0 0 000:00:00
11astounding.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
1288-165-5-23.subs.proxad.net 1 00.0 KB 0 0 000:00:00
132607:ff10:c8:594::e 2 00.0 KB 0 0 000:00:05
TOTAL: 13 301 00.0 KB 0 0 000:01:45

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.subs.proxad.net 1 00.0 KB 0 0 000:00:00
2unresolved 4 00.0 KB 0 0 000:00:04
3*.monitoring.internet-measurement.com 6 00.0 KB 0 0 000:00:09
4*.gegeweb.eu 288 00.0 KB 0 0 000:01:26
5? 2 00.0 KB 0 0 000:00:05
TOTAL: 5 301 00.0 KB 0 0 000:01:45

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 4.353 4.238 1.55300:01:26
fabulous.monitoring.internet-measurement.com 0.058 0.016 0.00000:00:06
2607:ff10:c8:594::e 0.041 0.036 0.00000:00:05
172.104.238.162 0.013 0.016 0.00000:00:02
astounding.monitoring.internet-measurement.com 0.010 0.014 0.00000:00:01
192.241.235.28 0.015 0.011 0.00000:00:00
88-165-5-23.subs.proxad.net 0.040 0.018 0.00000:00:00
194.165.16.10 0.024 0.028 0.00000:00:00
supportive.monitoring.internet-measurement.com 0.034 0.022 0.00000:00:00
wondrous.monitoring.internet-measurement.com 0.012 0.016 0.00000:00:00
fortunate.monitoring.internet-measurement.com 0.042 0.021 0.00000:00:00
45.227.254.8 0.023 0.005 0.00000:00:00
TOTAL: 12 4.665 4.441 1.55300:01:45

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
194.165.16.10 1
192.241.235.28 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
#003 1
MGLNDD_45.13.105.36_119 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.10 1 1
TOTAL: 1 1 1