Daily Usenet report for news.niel.me

Sep 12 04:15:01 -- Sep 13 04:15:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Sep 12 11:31:54 rpi-studio innd: filter: No Cancel-Key[sha1:2fC1BHAnMdOadzCWgiYcQ8J2qRw= sha256:TDMVnKFJir3VDrOY+Ab+c+ywdDTe/sVfC2o8WddbtAg=] matches Cancel-Lock[sha1:sDygZxAUw3qKsD/fDuX7S8jq2sY= sha256:DM2trraRyNWAsMPHcodHVCesfRSfz+7YR21gIEN3zv0=] target=<slrn11aa6g1.g22.lhh+news-no_spam@hercule.home.arpa>
Sep 12 17:39:14 rpi-studio innd: filter: No Cancel-Key[sha1:oBkVMjMZ9EG32JYzay7S3ViY7bY= sha256:8QhD41Ls62Hp8U27V4rWEklf/Zq8b6Bjc2Eh3VPo1PI=] matches Cancel-Lock[sha1:CciAppR7fXtJAqaSpvcYcV3FGGA= sha256:ADzgqvOVK14R7GJ/NBHnw0aDWBlRb0y/lmbIeXxQCU8=] target=<8fpaaldbjd80gm6rl5u8rnfh339idm46kb@kgbh.org>
Sep 13 00:41:46 rpi-studio innd: filter: No Cancel-Key[sha1:iGp2guERJaTpIJ09rwKkVidXtqA= sha256:K4CRAulO4dF6Sx8YRHgkBU22WzuG/yHtYwuLvgom6C8=] matches Cancel-Lock[sha1:PKoxQeD37Cx0HB86dn+wURHPvgs= sha256:e7IfFTC/i3s1HybtSSKbqV0DxUZJHP8Aohrnok/jOPY=] target=<j9kbaldahutdmuggo881us451bi69lu7v9@kgbh.org>
Sep 13 03:50:46 rpi-studio innd: filter: No Cancel-Key[sha1:5ViToNc8K+9/7Tbb62uksd1Cgtg= sha256:SMYJ6FCxkQDCqVEPAMOLUsMouTIkWnvW6YangRISPmg=] matches Cancel-Lock[sha1:izznggUGjWMVv+tEA+RpcOwgTTA= sha256:DIFuOWo7Syk816h+K3Zz7PzjXJpPMyDdG9ipGKscwt4=] target=<1184vfi$mcuf$1@gwaiyur.mb-net.net>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2126 26.7%379.3 KB 30.8%
innd 2008 25.2%346.3 KB 28.2%
innfeed 1999 25.1%300.6 KB 24.4%
nnrpd 1836 23.0%203.5 KB 16.5%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 7971 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 8148 50.4%
Negative hits 5887 36.4%
Do not exist 2121 13.1%
Cache misses 3 0.0%
TOTAL: 4 16159 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.001 0.0%1 1.000 1.000 1.000
article cancel00:00:00.002 0.0%3 0.000 0.667 1.000
article cleanup00:00:00.054 0.0%2122 0.000 0.025 0.500
article logging00:00:00.150 0.0%2123 0.000 0.071 0.600
article parse00:00:00.429 0.0%7633 0.000 0.056 0.300
article write00:00:00.951 0.0%1868 0.000 0.509 1.333
artlog/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.124 0.0%20041 0.000 0.006 0.048
hisgrep/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.001 0.0%1 1.000 1.000 1.000
hisgrep/perl00:00:00.001 0.0%5 0.000 0.200 1.000
hishave/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%1 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.272 0.0%16157 0.000 0.017 0.051
history sync00:00:00.052 0.0%359 0.000 0.145 1.000
history write00:00:00.359 0.0%2122 0.000 0.169 0.833
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:27.767100.0%21414 331.907 4036.040 60011.600
nntp read00:00:00.584 0.0%20791 0.000 0.028 0.250
overview write00:00:00.535 0.0%1868 0.000 0.286 3.333
perl filter00:00:14.312 0.0%2122 1.833 6.745 48.000
python filter00:00:00.008 0.0%2122 0.000 0.004 0.100
site send00:00:00.761 0.0%3780 0.000 0.201 0.524
TOTAL: 24:01:06.50124:00:46.363100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.012 0.0%1947 0.000 0.006 0.167
article prepare00:00:00.000 0.0%28 0.000 0.000 0.000
article read00:00:00.009 0.0%7 0.000 1.286 4.000
backlog stats00:00:00.136 0.0%22698 0.000 0.006 0.030
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.279 0.0%11226 0.000 0.025 0.080
data write00:00:00.588 0.0%9456 0.000 0.062 0.167
idle23:59:06.725100.0%22698 1235.988 3804.156 10168.661
status file00:00:11.046 0.0%356 1.000 31.028 91.000
TOTAL: 23:59:23.53623:59:18.795100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:17.151 38.0%1444 11.200 11.877 198.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.123 0.3%3223 0.000 0.038 0.182
TOTAL: 00:00:45.11600:00:17.274 38.3%----

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 5 1871 794 1077 0 42%23:44:53
2news.samoylyk.net 5 6265 598 5649 18 9%23:10:32
3news.nntp4.net 3 2197 232 1711 254 10%23:14:13
4news.glou.org 6 1149 108 1009 32 9%21:40:36
5nntp.terraraq.uk 6 4973 91 4866 16 1%22:58:13
6gegeweb-out.news.tnetconsulting.net 5 3670 18 3648 4 0%22:58:22
7usenet.pasdenom.info 17 630 11 619 0 1%22:09:47
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 72 20755 1852 18579 324 8%159:56:36
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.samoylyk.net5.0 MB0.0 KB214.0 KB5.2 MB 95%8.7 KB
2feed-out.gegeweb.org3.7 MB0.0 KB0.0 KB3.7 MB100%4.7 KB
3news.nntp4.net2.7 MB0.0 KB2.1 MB4.7 MB 56%10.0 KB
4news.glou.org355.5 KB0.0 KB124.6 KB480.1 KB 74%3.4 KB
5nntp.terraraq.uk218.1 KB0.0 KB51.6 KB269.7 KB 80%2.5 KB
6gegeweb-out.news.tnetconsulting.net66.7 KB0.0 KB67.6 KB134.2 KB 49%6.1 KB
7usenet.pasdenom.info21.3 KB0.0 KB0.0 KB21.3 KB100%1.9 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 812.0 MB0.0 KB2.5 MB14.5 MB 82%6.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 12 04:15:01 - 04:59:59 57 3.1% 0.02222.5 KB 1.8% 0.08
Sep 12 05:00:00 - 05:59:59 40 2.1% 0.01151.5 KB 1.2% 0.04
Sep 12 06:00:00 - 06:59:59 14 0.7% 0.0056.9 KB 0.5% 0.02
Sep 12 07:00:00 - 07:59:59 36 1.9% 0.01134.8 KB 1.1% 0.04
Sep 12 08:00:00 - 08:59:59 39 2.1% 0.01146.7 KB 1.2% 0.04
Sep 12 09:00:00 - 09:59:59 36 1.9% 0.01161.5 KB 1.3% 0.04
Sep 12 10:00:00 - 10:59:59 132 7.1% 0.044.3 MB 35.8% 1.23
Sep 12 11:00:00 - 11:59:59 114 6.1% 0.03773.8 KB 6.3% 0.21
Sep 12 12:00:00 - 12:59:59 89 4.8% 0.02410.6 KB 3.3% 0.11
Sep 12 13:00:00 - 13:59:59 88 4.7% 0.02359.1 KB 2.9% 0.10
Sep 12 14:00:00 - 14:59:59 83 4.4% 0.02410.1 KB 3.3% 0.11
Sep 12 15:00:00 - 15:59:59 82 4.4% 0.02255.9 KB 2.1% 0.07
Sep 12 16:00:00 - 16:59:59 77 4.1% 0.02364.6 KB 2.9% 0.10
Sep 12 17:00:00 - 17:59:59 121 6.5% 0.03627.0 KB 5.1% 0.17
Sep 12 18:00:00 - 18:59:59 127 6.8% 0.04444.1 KB 3.6% 0.12
Sep 12 19:00:00 - 19:59:59 109 5.8% 0.03473.7 KB 3.8% 0.13
Sep 12 20:00:00 - 20:59:59 137 7.3% 0.04556.0 KB 4.5% 0.15
Sep 12 21:00:00 - 21:59:59 112 6.0% 0.03595.9 KB 4.8% 0.17
Sep 12 22:00:00 - 22:59:59 77 4.1% 0.02580.9 KB 4.7% 0.16
Sep 12 23:00:00 - 23:59:59 80 4.3% 0.02304.0 KB 2.5% 0.08
Sep 13 00:00:00 - 00:59:59 57 3.1% 0.02231.8 KB 1.9% 0.06
Sep 13 01:00:00 - 01:59:59 56 3.0% 0.02236.0 KB 1.9% 0.07
Sep 13 02:00:00 - 02:59:59 43 2.3% 0.01208.7 KB 1.7% 0.06
Sep 13 03:00:00 - 03:59:59 48 2.6% 0.01178.3 KB 1.4% 0.05
Sep 13 04:00:00 - 04:15:01 14 0.7% 0.0248.0 KB 0.4% 0.05
TOTAL: 24:00:00 1868 100.0% 0.0212.1 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 221 131 0 0 1 0 0 0 89
2news.glou.org 32 0 0 0 0 0 0 0 32
3nntp.terraraq.uk 1 0 0 0 0 0 0 0 1
TOTAL: 3 254 131 0 0 1 0 0 0 122

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 119
linux.kernel.git 12
TOTAL: 2 131

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
soc.culture.hawaii 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 85
Bad path (news.giganews.com.POSTED) 33
No Cancel-Key matches Cancel-Lock. target=<8fpaaldbjd80gm6rl5u8rnfh339idm46kb@kgbh.org> 1
No Cancel-Key matches Cancel-Lock. target=<j9kbaldahutdmuggo881us451bi69lu7v9@kgbh.org> 1
Too many newsgroups (meow) 1
TOTAL: 5 121

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.nntp4.net 1
TOTAL: 1 1
TOTAL: 1 1

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.pasdenom.info 1267 2 1237 2 0 0 0%23:33:27
2news.gegeweb.org 1134 1 1074 0 0 0 0%23:03:11
3news.samoylyk.net 1228 0 1144 0 0 3 0%23:21:51
4i2pn.org 0 0 0 0 0 1845 0%24:00:00
5nntp.terraraq.uk 995 0 965 0 0 0 0%23:09:56
6news.aioe.org 0 0 0 0 0 1578 0%24:00:00
7news.nntp4.net 1662 0 1617 0 0 0 0%23:34:37
8news.tnetconsulting.net 3237 0 1854 2 0 76 0%23:45:35
TOTAL: 8 9523 3 7891 4 0 3502 0%188:28:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.gegeweb.org31.3 KB0.0 KB31.3 KB0.0 KB/s31.3 KB23:03:11
2news.pasdenom.info4.8 KB5.4 KB10.3 KB0.0 KB/s2.6 KB23:33:27
3news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:21:51
4i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
5nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:09:56
6news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
7news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:34:37
8news.tnetconsulting.net0.0 KB35.5 KB35.5 KB0.0 KB/s17.7 KB23:45:35
TOTAL: 836.2 KB40.9 KB77.0 KB0.0 KB/s11.0 KB188:28:37
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1104.152.52.206 1 00.0 KB 0 0 000:00:00
2104.152.52.214 4 00.0 KB 0 0 000:00:02
3205.210.31.207 1 00.0 KB 0 0 000:00:00
4104.152.52.210 1 00.0 KB 0 0 000:00:01
5104.152.52.208 1 00.0 KB 0 0 000:00:00
6104.152.52.209 1 00.0 KB 0 0 000:00:01
7104.152.52.211 1 00.0 KB 0 0 000:00:01
8104.152.52.212 2 00.0 KB 0 0 000:00:00
9193-176-31-249.infrawat.ch 1 00.0 KB 0 0 000:00:00
10104.152.52.200 1 00.0 KB 0 0 000:00:00
11104.152.52.204 1 00.0 KB 0 0 000:00:00
12mail.gegeweb.eu 288 00.0 KB 0 0 000:00:32
13194-88-98-107.infrawat.ch 1 00.0 KB 0 0 000:00:00
TOTAL: 13 304 00.0 KB 0 0 000:00:44

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 14 00.0 KB 0 0 000:00:11
2*.infrawat.ch 2 00.0 KB 0 0 000:00:00
3*.gegeweb.eu 288 00.0 KB 0 0 000:00:32
TOTAL: 3 304 00.0 KB 0 0 000:00:44

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.043 4.523 1.72000:00:32
104.152.52.214 0.152 0.045 0.00000:00:02
104.152.52.211 0.020 0.008 0.00000:00:01
104.152.52.209 0.041 0.019 0.00000:00:01
104.152.52.210 0.036 0.012 0.00000:00:01
104.152.52.204 0.040 0.008 0.00000:00:00
104.152.52.200 0.042 0.005 0.00000:00:00
104.152.52.206 0.035 0.012 0.00000:00:00
205.210.31.207 0.026 0.026 0.00000:00:00
104.152.52.212 0.053 0.028 0.00000:00:00
104.152.52.208 0.015 0.012 0.00000:00:00
194-88-98-107.infrawat.ch 0.028 0.020 0.00000:00:00
193-176-31-249.infrawat.ch 0.030 0.023 0.00000:00:00
TOTAL: 13 5.561 4.741 1.72000:00:44

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
104.152.52.210 5
104.152.52.204 2
TOTAL: 2 7

NNRP unrecognized commands (by command):

CommandCount
Accept: */* 1
MAIL FROM:<relaycheck_please_ignore@prot... 1
Host: 45.13.105.36 1
GET / HTTP/1.1 1
EHLO 45.13.105.36 1
User-Agent: curl/7.61.1 1
Connection: close 1
TOTAL: 7 7

NNRP client timeouts [Top 20]:

SystemConnPeer
193-176-31-249.infrawat.ch 1 1
TOTAL: 1 1 3
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me